Dec 02 15:40:19.981 INFO testing srt-transport-0.2.0 against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 02 15:40:19.981 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 15:40:20.168 INFO [stdout] dd72db3655ddddcec26d7e72fba1eaed31188fbd8cda6c8fcfc9ffade1962cb2
Dec 02 15:40:20.170 INFO running `"docker" "start" "-a" "dd72db3655ddddcec26d7e72fba1eaed31188fbd8cda6c8fcfc9ffade1962cb2"`
Dec 02 15:40:20.555 INFO [stderr] usermod: no changes
Dec 02 15:40:20.742 INFO [stderr]    Compiling futures-timer v0.1.1
Dec 02 15:40:20.743 INFO [stderr]    Compiling simple_logger v0.5.0
Dec 02 15:40:21.978 INFO [stderr]    Compiling srt-transport v0.2.0 (/source)
Dec 02 15:40:25.335 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.335 INFO [stderr]    --> src/packet.rs:175:39
Dec 02 15:40:25.335 INFO [stderr]     |
Dec 02 15:40:25.335 INFO [stderr] 175 |                 let udt_version = buf.get_i32::<BigEndian>();
Dec 02 15:40:25.335 INFO [stderr]     |                                       ^^^^^^^
Dec 02 15:40:25.335 INFO [stderr]     |
Dec 02 15:40:25.335 INFO [stderr]     = note: #[warn(deprecated)] on by default
Dec 02 15:40:25.335 INFO [stderr] 
Dec 02 15:40:25.336 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.336 INFO [stderr]    --> src/packet.rs:176:58
Dec 02 15:40:25.336 INFO [stderr]     |
Dec 02 15:40:25.336 INFO [stderr] 176 |                 let sock_type = SocketType::from_i32(buf.get_i32::<BigEndian>())?;
Dec 02 15:40:25.336 INFO [stderr]     |                                                          ^^^^^^^
Dec 02 15:40:25.336 INFO [stderr] 
Dec 02 15:40:25.336 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.336 INFO [stderr]    --> src/packet.rs:177:50
Dec 02 15:40:25.336 INFO [stderr]     |
Dec 02 15:40:25.336 INFO [stderr] 177 |                 let init_seq_num = SeqNumber(buf.get_i32::<BigEndian>());
Dec 02 15:40:25.336 INFO [stderr]     |                                                  ^^^^^^^
Dec 02 15:40:25.336 INFO [stderr] 
Dec 02 15:40:25.337 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.337 INFO [stderr]    --> src/packet.rs:178:43
Dec 02 15:40:25.337 INFO [stderr]     |
Dec 02 15:40:25.337 INFO [stderr] 178 |                 let max_packet_size = buf.get_i32::<BigEndian>();
Dec 02 15:40:25.337 INFO [stderr]     |                                           ^^^^^^^
Dec 02 15:40:25.337 INFO [stderr] 
Dec 02 15:40:25.337 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.337 INFO [stderr]    --> src/packet.rs:179:41
Dec 02 15:40:25.337 INFO [stderr]     |
Dec 02 15:40:25.338 INFO [stderr] 179 |                 let max_flow_size = buf.get_i32::<BigEndian>();
Dec 02 15:40:25.338 INFO [stderr]     |                                         ^^^^^^^
Dec 02 15:40:25.338 INFO [stderr] 
Dec 02 15:40:25.338 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.338 INFO [stderr]    --> src/packet.rs:180:68
Dec 02 15:40:25.338 INFO [stderr]     |
Dec 02 15:40:25.338 INFO [stderr] 180 |                 let connection_type = ConnectionType::from_i32(buf.get_i32::<BigEndian>())?;
Dec 02 15:40:25.338 INFO [stderr]     |                                                                    ^^^^^^^
Dec 02 15:40:25.338 INFO [stderr] 
Dec 02 15:40:25.338 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.338 INFO [stderr]    --> src/packet.rs:181:46
Dec 02 15:40:25.338 INFO [stderr]     |
Dec 02 15:40:25.338 INFO [stderr] 181 |                 let socket_id = SocketID(buf.get_i32::<BigEndian>());
Dec 02 15:40:25.338 INFO [stderr]     |                                              ^^^^^^^
Dec 02 15:40:25.338 INFO [stderr] 
Dec 02 15:40:25.338 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.338 INFO [stderr]    --> src/packet.rs:182:38
Dec 02 15:40:25.338 INFO [stderr]     |
Dec 02 15:40:25.338 INFO [stderr] 182 |                 let syn_cookie = buf.get_i32::<BigEndian>();
Dec 02 15:40:25.338 INFO [stderr]     |                                      ^^^^^^^
Dec 02 15:40:25.338 INFO [stderr] 
Dec 02 15:40:25.356 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.357 INFO [stderr]    --> src/packet.rs:212:48
Dec 02 15:40:25.357 INFO [stderr]     |
Dec 02 15:40:25.357 INFO [stderr] 212 |                 let ack_number = SeqNumber(buf.get_i32::<BigEndian>());
Dec 02 15:40:25.357 INFO [stderr]     |                                                ^^^^^^^
Dec 02 15:40:25.357 INFO [stderr] 
Dec 02 15:40:25.357 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.357 INFO [stderr]    --> src/packet.rs:217:34
Dec 02 15:40:25.357 INFO [stderr]     |
Dec 02 15:40:25.357 INFO [stderr] 217 |                         Some(buf.get_i32::<BigEndian>())
Dec 02 15:40:25.357 INFO [stderr]     |                                  ^^^^^^^
Dec 02 15:40:25.357 INFO [stderr] 
Dec 02 15:40:25.358 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.358 INFO [stderr]    --> src/packet.rs:245:40
Dec 02 15:40:25.358 INFO [stderr]     |
Dec 02 15:40:25.358 INFO [stderr] 245 |                     loss_info.push(buf.get_i32::<BigEndian>());
Dec 02 15:40:25.358 INFO [stderr]     |                                        ^^^^^^^
Dec 02 15:40:25.358 INFO [stderr] 
Dec 02 15:40:25.364 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.364 INFO [stderr]    --> src/packet.rs:295:22
Dec 02 15:40:25.364 INFO [stderr]     |
Dec 02 15:40:25.364 INFO [stderr] 295 |                 into.put_i32::<BigEndian>(c.udt_version);
Dec 02 15:40:25.364 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.364 INFO [stderr] 
Dec 02 15:40:25.364 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.364 INFO [stderr]    --> src/packet.rs:296:22
Dec 02 15:40:25.364 INFO [stderr]     |
Dec 02 15:40:25.364 INFO [stderr] 296 |                 into.put_i32::<BigEndian>(c.sock_type.to_i32());
Dec 02 15:40:25.364 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.364 INFO [stderr] 
Dec 02 15:40:25.366 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.366 INFO [stderr]    --> src/packet.rs:297:22
Dec 02 15:40:25.366 INFO [stderr]     |
Dec 02 15:40:25.366 INFO [stderr] 297 |                 into.put_i32::<BigEndian>(c.init_seq_num.0);
Dec 02 15:40:25.366 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.366 INFO [stderr] 
Dec 02 15:40:25.366 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.366 INFO [stderr]    --> src/packet.rs:298:22
Dec 02 15:40:25.366 INFO [stderr]     |
Dec 02 15:40:25.366 INFO [stderr] 298 |                 into.put_i32::<BigEndian>(c.max_packet_size);
Dec 02 15:40:25.366 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.366 INFO [stderr] 
Dec 02 15:40:25.366 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.366 INFO [stderr]    --> src/packet.rs:299:22
Dec 02 15:40:25.366 INFO [stderr]     |
Dec 02 15:40:25.366 INFO [stderr] 299 |                 into.put_i32::<BigEndian>(c.max_flow_size);
Dec 02 15:40:25.366 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.366 INFO [stderr] 
Dec 02 15:40:25.366 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.366 INFO [stderr]    --> src/packet.rs:300:22
Dec 02 15:40:25.366 INFO [stderr]     |
Dec 02 15:40:25.366 INFO [stderr] 300 |                 into.put_i32::<BigEndian>(c.connection_type.to_i32());
Dec 02 15:40:25.366 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.366 INFO [stderr] 
Dec 02 15:40:25.366 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.366 INFO [stderr]    --> src/packet.rs:301:22
Dec 02 15:40:25.366 INFO [stderr]     |
Dec 02 15:40:25.366 INFO [stderr] 301 |                 into.put_i32::<BigEndian>(c.socket_id.0);
Dec 02 15:40:25.367 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.367 INFO [stderr] 
Dec 02 15:40:25.367 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.367 INFO [stderr]    --> src/packet.rs:302:22
Dec 02 15:40:25.367 INFO [stderr]     |
Dec 02 15:40:25.367 INFO [stderr] 302 |                 into.put_i32::<BigEndian>(c.syn_cookie);
Dec 02 15:40:25.367 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.367 INFO [stderr] 
Dec 02 15:40:25.367 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.367 INFO [stderr]    --> src/packet.rs:315:22
Dec 02 15:40:25.367 INFO [stderr]     |
Dec 02 15:40:25.367 INFO [stderr] 315 |                 into.put_i32::<BigEndian>(c.ack_number.0);
Dec 02 15:40:25.367 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.367 INFO [stderr] 
Dec 02 15:40:25.367 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.367 INFO [stderr]    --> src/packet.rs:316:22
Dec 02 15:40:25.367 INFO [stderr]     |
Dec 02 15:40:25.367 INFO [stderr] 316 |                 into.put_i32::<BigEndian>(c.rtt.unwrap_or(10_000));
Dec 02 15:40:25.367 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.367 INFO [stderr] 
Dec 02 15:40:25.368 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.368 INFO [stderr]    --> src/packet.rs:317:22
Dec 02 15:40:25.368 INFO [stderr]     |
Dec 02 15:40:25.368 INFO [stderr] 317 |                 into.put_i32::<BigEndian>(c.rtt_variance.unwrap_or(50_000));
Dec 02 15:40:25.368 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.368 INFO [stderr] 
Dec 02 15:40:25.368 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.368 INFO [stderr]    --> src/packet.rs:318:22
Dec 02 15:40:25.368 INFO [stderr]     |
Dec 02 15:40:25.368 INFO [stderr] 318 |                 into.put_i32::<BigEndian>(c.buffer_available.unwrap_or(8175)); // TODO: better defaults
Dec 02 15:40:25.368 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.368 INFO [stderr] 
Dec 02 15:40:25.368 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.368 INFO [stderr]    --> src/packet.rs:319:22
Dec 02 15:40:25.368 INFO [stderr]     |
Dec 02 15:40:25.368 INFO [stderr] 319 |                 into.put_i32::<BigEndian>(c.packet_recv_rate.unwrap_or(10_000));
Dec 02 15:40:25.368 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.368 INFO [stderr] 
Dec 02 15:40:25.368 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.368 INFO [stderr]    --> src/packet.rs:320:22
Dec 02 15:40:25.368 INFO [stderr]     |
Dec 02 15:40:25.368 INFO [stderr] 320 |                 into.put_i32::<BigEndian>(c.est_link_cap.unwrap_or(1_000));
Dec 02 15:40:25.368 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.368 INFO [stderr] 
Dec 02 15:40:25.382 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.383 INFO [stderr]    --> src/packet.rs:323:22
Dec 02 15:40:25.383 INFO [stderr]     |
Dec 02 15:40:25.383 INFO [stderr] 323 |                 into.put_i32::<BigEndian>(loss);
Dec 02 15:40:25.383 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.383 INFO [stderr] 
Dec 02 15:40:25.400 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.400 INFO [stderr]    --> src/packet.rs:507:60
Dec 02 15:40:25.400 INFO [stderr]     |
Dec 02 15:40:25.400 INFO [stderr] 507 |             let seq_number = SeqNumber(Cursor::new(first4).get_i32::<BigEndian>());
Dec 02 15:40:25.400 INFO [stderr]     |                                                            ^^^^^^^
Dec 02 15:40:25.400 INFO [stderr] 
Dec 02 15:40:25.400 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.400 INFO [stderr]    --> src/packet.rs:510:35
Dec 02 15:40:25.400 INFO [stderr]     |
Dec 02 15:40:25.400 INFO [stderr] 510 |             let second_line = buf.get_i32::<BigEndian>();
Dec 02 15:40:25.400 INFO [stderr]     |                                   ^^^^^^^
Dec 02 15:40:25.400 INFO [stderr] 
Dec 02 15:40:25.401 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.401 INFO [stderr]    --> src/packet.rs:519:33
Dec 02 15:40:25.401 INFO [stderr]     |
Dec 02 15:40:25.401 INFO [stderr] 519 |             let timestamp = buf.get_i32::<BigEndian>();
Dec 02 15:40:25.401 INFO [stderr]     |                                 ^^^^^^^
Dec 02 15:40:25.401 INFO [stderr] 
Dec 02 15:40:25.401 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.401 INFO [stderr]    --> src/packet.rs:520:44
Dec 02 15:40:25.401 INFO [stderr]     |
Dec 02 15:40:25.401 INFO [stderr] 520 |             let dest_sockid = SocketID(buf.get_i32::<BigEndian>());
Dec 02 15:40:25.401 INFO [stderr]     |                                            ^^^^^^^
Dec 02 15:40:25.401 INFO [stderr] 
Dec 02 15:40:25.402 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.402 INFO [stderr]    --> src/packet.rs:534:32
Dec 02 15:40:25.402 INFO [stderr]     |
Dec 02 15:40:25.402 INFO [stderr] 534 |             let add_info = buf.get_i32::<BigEndian>();
Dec 02 15:40:25.402 INFO [stderr]     |                                ^^^^^^^
Dec 02 15:40:25.402 INFO [stderr] 
Dec 02 15:40:25.402 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.402 INFO [stderr]    --> src/packet.rs:535:33
Dec 02 15:40:25.402 INFO [stderr]     |
Dec 02 15:40:25.402 INFO [stderr] 535 |             let timestamp = buf.get_i32::<BigEndian>();
Dec 02 15:40:25.402 INFO [stderr]     |                                 ^^^^^^^
Dec 02 15:40:25.402 INFO [stderr] 
Dec 02 15:40:25.402 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:25.402 INFO [stderr]    --> src/packet.rs:536:35
Dec 02 15:40:25.402 INFO [stderr]     |
Dec 02 15:40:25.402 INFO [stderr] 536 |             let dest_sockid = buf.get_i32::<BigEndian>();
Dec 02 15:40:25.402 INFO [stderr]     |                                   ^^^^^^^
Dec 02 15:40:25.402 INFO [stderr] 
Dec 02 15:40:25.404 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i16': use put_i16_be or put_i16_le
Dec 02 15:40:25.404 INFO [stderr]    --> src/packet.rs:555:22
Dec 02 15:40:25.404 INFO [stderr]     |
Dec 02 15:40:25.404 INFO [stderr] 555 |                 into.put_i16::<BigEndian>((i16::from(control_type.id_byte())) | (0b1 << 15));
Dec 02 15:40:25.404 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.404 INFO [stderr] 
Dec 02 15:40:25.410 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i16': use put_i16_be or put_i16_le
Dec 02 15:40:25.410 INFO [stderr]    --> src/packet.rs:558:22
Dec 02 15:40:25.410 INFO [stderr]     |
Dec 02 15:40:25.410 INFO [stderr] 558 |                 into.put_i16::<BigEndian>(0);
Dec 02 15:40:25.410 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.410 INFO [stderr] 
Dec 02 15:40:25.410 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.410 INFO [stderr]    --> src/packet.rs:561:22
Dec 02 15:40:25.410 INFO [stderr]     |
Dec 02 15:40:25.410 INFO [stderr] 561 |                 into.put_i32::<BigEndian>(control_type.additional_info());
Dec 02 15:40:25.410 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.410 INFO [stderr] 
Dec 02 15:40:25.410 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.410 INFO [stderr]    --> src/packet.rs:564:22
Dec 02 15:40:25.410 INFO [stderr]     |
Dec 02 15:40:25.410 INFO [stderr] 564 |                 into.put_i32::<BigEndian>(*timestamp);
Dec 02 15:40:25.410 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.410 INFO [stderr] 
Dec 02 15:40:25.410 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.410 INFO [stderr]    --> src/packet.rs:567:22
Dec 02 15:40:25.410 INFO [stderr]     |
Dec 02 15:40:25.410 INFO [stderr] 567 |                 into.put_i32::<BigEndian>(dest_sockid.0);
Dec 02 15:40:25.410 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.410 INFO [stderr] 
Dec 02 15:40:25.411 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.411 INFO [stderr]    --> src/packet.rs:581:22
Dec 02 15:40:25.411 INFO [stderr]     |
Dec 02 15:40:25.411 INFO [stderr] 581 |                 into.put_i32::<BigEndian>(seq_number.0);
Dec 02 15:40:25.411 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.411 INFO [stderr] 
Dec 02 15:40:25.411 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.411 INFO [stderr]    --> src/packet.rs:582:22
Dec 02 15:40:25.411 INFO [stderr]     |
Dec 02 15:40:25.411 INFO [stderr] 582 |                 into.put_i32::<BigEndian>(
Dec 02 15:40:25.411 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.411 INFO [stderr] 
Dec 02 15:40:25.412 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.412 INFO [stderr]    --> src/packet.rs:587:22
Dec 02 15:40:25.412 INFO [stderr]     |
Dec 02 15:40:25.412 INFO [stderr] 587 |                 into.put_i32::<BigEndian>(*timestamp);
Dec 02 15:40:25.412 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.412 INFO [stderr] 
Dec 02 15:40:25.412 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:25.412 INFO [stderr]    --> src/packet.rs:588:22
Dec 02 15:40:25.412 INFO [stderr]     |
Dec 02 15:40:25.412 INFO [stderr] 588 |                 into.put_i32::<BigEndian>(dest_sockid.0);
Dec 02 15:40:25.412 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:25.412 INFO [stderr] 
Dec 02 15:40:25.732 INFO [stderr] warning: field is never used: `local_public`
Dec 02 15:40:25.732 INFO [stderr]   --> src/pending_connection/rendezvous.rs:10:5
Dec 02 15:40:25.732 INFO [stderr]    |
Dec 02 15:40:25.732 INFO [stderr] 10 |     local_public: SocketAddr,
Dec 02 15:40:25.732 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:25.732 INFO [stderr]    |
Dec 02 15:40:25.732 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 02 15:40:25.732 INFO [stderr] 
Dec 02 15:40:25.732 INFO [stderr] warning: field is never used: `remote_public`
Dec 02 15:40:25.732 INFO [stderr]   --> src/pending_connection/rendezvous.rs:11:5
Dec 02 15:40:25.732 INFO [stderr]    |
Dec 02 15:40:25.732 INFO [stderr] 11 |     remote_public: SocketAddr,
Dec 02 15:40:25.732 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:25.732 INFO [stderr] 
Dec 02 15:40:25.732 INFO [stderr] warning: field is never used: `sock`
Dec 02 15:40:25.732 INFO [stderr]   --> src/pending_connection/rendezvous.rs:12:5
Dec 02 15:40:25.732 INFO [stderr]    |
Dec 02 15:40:25.732 INFO [stderr] 12 |     sock: T,
Dec 02 15:40:25.732 INFO [stderr]    |     ^^^^^^^
Dec 02 15:40:25.732 INFO [stderr] 
Dec 02 15:40:30.080 INFO [stderr] warning: use of deprecated item 'tokio::executor::current_thread': use tokio-current-thread crate or functions in tokio::runtime::current_thread instead
Dec 02 15:40:30.080 INFO [stderr]   --> src/bin.rs:18:13
Dec 02 15:40:30.080 INFO [stderr]    |
Dec 02 15:40:30.080 INFO [stderr] 18 | use tokio::{executor::current_thread, net::{UdpFramed, UdpSocket}};
Dec 02 15:40:30.080 INFO [stderr]    |             ^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:30.080 INFO [stderr]    |
Dec 02 15:40:30.080 INFO [stderr]    = note: #[warn(deprecated)] on by default
Dec 02 15:40:30.080 INFO [stderr] 
Dec 02 15:40:30.080 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::BytesCodec': Moved to tokio-codec
Dec 02 15:40:30.080 INFO [stderr]   --> src/bin.rs:19:5
Dec 02 15:40:30.080 INFO [stderr]    |
Dec 02 15:40:30.080 INFO [stderr] 19 | use tokio_io::codec::BytesCodec;
Dec 02 15:40:30.080 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:30.080 INFO [stderr] 
Dec 02 15:40:30.080 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::BytesCodec': Moved to tokio-codec
Dec 02 15:40:30.080 INFO [stderr]   --> src/bin.rs:89:25
Dec 02 15:40:30.080 INFO [stderr]    |
Dec 02 15:40:30.080 INFO [stderr] 89 |                         BytesCodec::new(),
Dec 02 15:40:30.080 INFO [stderr]    |                         ^^^^^^^^^^^^^^^
Dec 02 15:40:30.080 INFO [stderr] 
Dec 02 15:40:30.080 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::BytesCodec': Moved to tokio-codec
Dec 02 15:40:30.080 INFO [stderr]    --> src/bin.rs:142:21
Dec 02 15:40:30.080 INFO [stderr]     |
Dec 02 15:40:30.080 INFO [stderr] 142 |                     BytesCodec::new(),
Dec 02 15:40:30.080 INFO [stderr]     |                     ^^^^^^^^^^^^^^^
Dec 02 15:40:30.080 INFO [stderr] 
Dec 02 15:40:30.080 INFO [stderr] warning: use of deprecated item 'tokio::executor::current_thread::run': use block_on_all instead
Dec 02 15:40:30.080 INFO [stderr]    --> src/bin.rs:159:5
Dec 02 15:40:30.080 INFO [stderr]     |
Dec 02 15:40:30.080 INFO [stderr] 159 |     current_thread::run(|_| {
Dec 02 15:40:30.081 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:30.081 INFO [stderr] 
Dec 02 15:40:34.049 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.47s
Dec 02 15:40:34.059 INFO [stderr] su: No module specific data is present
Dec 02 15:40:35.058 INFO running `"docker" "inspect" "dd72db3655ddddcec26d7e72fba1eaed31188fbd8cda6c8fcfc9ffade1962cb2"`
Dec 02 15:40:35.304 INFO running `"docker" "rm" "-f" "dd72db3655ddddcec26d7e72fba1eaed31188fbd8cda6c8fcfc9ffade1962cb2"`
Dec 02 15:40:35.553 INFO [stdout] dd72db3655ddddcec26d7e72fba1eaed31188fbd8cda6c8fcfc9ffade1962cb2
Dec 02 15:40:35.555 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 15:40:35.930 INFO [stdout] 3e713e53ee55da1603721e97f3e7e3de91705ff63160dfd0e874e5f38443ec4d
Dec 02 15:40:35.932 INFO running `"docker" "start" "-a" "3e713e53ee55da1603721e97f3e7e3de91705ff63160dfd0e874e5f38443ec4d"`
Dec 02 15:40:36.541 INFO [stderr] usermod: no changes
Dec 02 15:40:36.667 INFO [stderr]    Compiling srt-transport v0.2.0 (/source)
Dec 02 15:40:40.588 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.588 INFO [stderr]    --> src/packet.rs:175:39
Dec 02 15:40:40.588 INFO [stderr]     |
Dec 02 15:40:40.588 INFO [stderr] 175 |                 let udt_version = buf.get_i32::<BigEndian>();
Dec 02 15:40:40.589 INFO [stderr]     |                                       ^^^^^^^
Dec 02 15:40:40.589 INFO [stderr]     |
Dec 02 15:40:40.589 INFO [stderr]     = note: #[warn(deprecated)] on by default
Dec 02 15:40:40.589 INFO [stderr] 
Dec 02 15:40:40.589 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.589 INFO [stderr]    --> src/packet.rs:176:58
Dec 02 15:40:40.589 INFO [stderr]     |
Dec 02 15:40:40.589 INFO [stderr] 176 |                 let sock_type = SocketType::from_i32(buf.get_i32::<BigEndian>())?;
Dec 02 15:40:40.589 INFO [stderr]     |                                                          ^^^^^^^
Dec 02 15:40:40.589 INFO [stderr] 
Dec 02 15:40:40.589 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.589 INFO [stderr]    --> src/packet.rs:177:50
Dec 02 15:40:40.589 INFO [stderr]     |
Dec 02 15:40:40.589 INFO [stderr] 177 |                 let init_seq_num = SeqNumber(buf.get_i32::<BigEndian>());
Dec 02 15:40:40.589 INFO [stderr]     |                                                  ^^^^^^^
Dec 02 15:40:40.589 INFO [stderr] 
Dec 02 15:40:40.589 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.589 INFO [stderr]    --> src/packet.rs:178:43
Dec 02 15:40:40.589 INFO [stderr]     |
Dec 02 15:40:40.589 INFO [stderr] 178 |                 let max_packet_size = buf.get_i32::<BigEndian>();
Dec 02 15:40:40.589 INFO [stderr]     |                                           ^^^^^^^
Dec 02 15:40:40.589 INFO [stderr] 
Dec 02 15:40:40.589 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.589 INFO [stderr]    --> src/packet.rs:179:41
Dec 02 15:40:40.589 INFO [stderr]     |
Dec 02 15:40:40.589 INFO [stderr] 179 |                 let max_flow_size = buf.get_i32::<BigEndian>();
Dec 02 15:40:40.589 INFO [stderr]     |                                         ^^^^^^^
Dec 02 15:40:40.589 INFO [stderr] 
Dec 02 15:40:40.589 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.589 INFO [stderr]    --> src/packet.rs:180:68
Dec 02 15:40:40.589 INFO [stderr]     |
Dec 02 15:40:40.589 INFO [stderr] 180 |                 let connection_type = ConnectionType::from_i32(buf.get_i32::<BigEndian>())?;
Dec 02 15:40:40.589 INFO [stderr]     |                                                                    ^^^^^^^
Dec 02 15:40:40.589 INFO [stderr] 
Dec 02 15:40:40.589 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.589 INFO [stderr]    --> src/packet.rs:181:46
Dec 02 15:40:40.589 INFO [stderr]     |
Dec 02 15:40:40.589 INFO [stderr] 181 |                 let socket_id = SocketID(buf.get_i32::<BigEndian>());
Dec 02 15:40:40.589 INFO [stderr]     |                                              ^^^^^^^
Dec 02 15:40:40.589 INFO [stderr] 
Dec 02 15:40:40.589 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.589 INFO [stderr]    --> src/packet.rs:182:38
Dec 02 15:40:40.589 INFO [stderr]     |
Dec 02 15:40:40.589 INFO [stderr] 182 |                 let syn_cookie = buf.get_i32::<BigEndian>();
Dec 02 15:40:40.589 INFO [stderr]     |                                      ^^^^^^^
Dec 02 15:40:40.589 INFO [stderr] 
Dec 02 15:40:40.599 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.599 INFO [stderr]    --> src/packet.rs:212:48
Dec 02 15:40:40.599 INFO [stderr]     |
Dec 02 15:40:40.599 INFO [stderr] 212 |                 let ack_number = SeqNumber(buf.get_i32::<BigEndian>());
Dec 02 15:40:40.599 INFO [stderr]     |                                                ^^^^^^^
Dec 02 15:40:40.599 INFO [stderr] 
Dec 02 15:40:40.599 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.599 INFO [stderr]    --> src/packet.rs:217:34
Dec 02 15:40:40.599 INFO [stderr]     |
Dec 02 15:40:40.599 INFO [stderr] 217 |                         Some(buf.get_i32::<BigEndian>())
Dec 02 15:40:40.599 INFO [stderr]     |                                  ^^^^^^^
Dec 02 15:40:40.599 INFO [stderr] 
Dec 02 15:40:40.603 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.603 INFO [stderr]    --> src/packet.rs:245:40
Dec 02 15:40:40.603 INFO [stderr]     |
Dec 02 15:40:40.603 INFO [stderr] 245 |                     loss_info.push(buf.get_i32::<BigEndian>());
Dec 02 15:40:40.603 INFO [stderr]     |                                        ^^^^^^^
Dec 02 15:40:40.603 INFO [stderr] 
Dec 02 15:40:40.608 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.608 INFO [stderr]    --> src/packet.rs:295:22
Dec 02 15:40:40.608 INFO [stderr]     |
Dec 02 15:40:40.608 INFO [stderr] 295 |                 into.put_i32::<BigEndian>(c.udt_version);
Dec 02 15:40:40.608 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.608 INFO [stderr] 
Dec 02 15:40:40.608 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.608 INFO [stderr]    --> src/packet.rs:296:22
Dec 02 15:40:40.608 INFO [stderr]     |
Dec 02 15:40:40.608 INFO [stderr] 296 |                 into.put_i32::<BigEndian>(c.sock_type.to_i32());
Dec 02 15:40:40.608 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.608 INFO [stderr] 
Dec 02 15:40:40.608 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.608 INFO [stderr]    --> src/packet.rs:297:22
Dec 02 15:40:40.608 INFO [stderr]     |
Dec 02 15:40:40.608 INFO [stderr] 297 |                 into.put_i32::<BigEndian>(c.init_seq_num.0);
Dec 02 15:40:40.608 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.608 INFO [stderr] 
Dec 02 15:40:40.608 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.608 INFO [stderr]    --> src/packet.rs:298:22
Dec 02 15:40:40.608 INFO [stderr]     |
Dec 02 15:40:40.608 INFO [stderr] 298 |                 into.put_i32::<BigEndian>(c.max_packet_size);
Dec 02 15:40:40.608 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.608 INFO [stderr] 
Dec 02 15:40:40.611 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.611 INFO [stderr]    --> src/packet.rs:299:22
Dec 02 15:40:40.611 INFO [stderr]     |
Dec 02 15:40:40.611 INFO [stderr] 299 |                 into.put_i32::<BigEndian>(c.max_flow_size);
Dec 02 15:40:40.611 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.611 INFO [stderr] 
Dec 02 15:40:40.611 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.611 INFO [stderr]    --> src/packet.rs:300:22
Dec 02 15:40:40.611 INFO [stderr]     |
Dec 02 15:40:40.611 INFO [stderr] 300 |                 into.put_i32::<BigEndian>(c.connection_type.to_i32());
Dec 02 15:40:40.611 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.611 INFO [stderr] 
Dec 02 15:40:40.611 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.611 INFO [stderr]    --> src/packet.rs:301:22
Dec 02 15:40:40.611 INFO [stderr]     |
Dec 02 15:40:40.611 INFO [stderr] 301 |                 into.put_i32::<BigEndian>(c.socket_id.0);
Dec 02 15:40:40.611 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.611 INFO [stderr] 
Dec 02 15:40:40.611 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.611 INFO [stderr]    --> src/packet.rs:302:22
Dec 02 15:40:40.611 INFO [stderr]     |
Dec 02 15:40:40.611 INFO [stderr] 302 |                 into.put_i32::<BigEndian>(c.syn_cookie);
Dec 02 15:40:40.611 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.611 INFO [stderr] 
Dec 02 15:40:40.612 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.612 INFO [stderr]    --> src/packet.rs:315:22
Dec 02 15:40:40.612 INFO [stderr]     |
Dec 02 15:40:40.612 INFO [stderr] 315 |                 into.put_i32::<BigEndian>(c.ack_number.0);
Dec 02 15:40:40.612 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.612 INFO [stderr] 
Dec 02 15:40:40.612 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.612 INFO [stderr]    --> src/packet.rs:316:22
Dec 02 15:40:40.612 INFO [stderr]     |
Dec 02 15:40:40.612 INFO [stderr] 316 |                 into.put_i32::<BigEndian>(c.rtt.unwrap_or(10_000));
Dec 02 15:40:40.612 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.612 INFO [stderr] 
Dec 02 15:40:40.612 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.612 INFO [stderr]    --> src/packet.rs:317:22
Dec 02 15:40:40.612 INFO [stderr]     |
Dec 02 15:40:40.612 INFO [stderr] 317 |                 into.put_i32::<BigEndian>(c.rtt_variance.unwrap_or(50_000));
Dec 02 15:40:40.612 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.612 INFO [stderr] 
Dec 02 15:40:40.612 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.612 INFO [stderr]    --> src/packet.rs:318:22
Dec 02 15:40:40.612 INFO [stderr]     |
Dec 02 15:40:40.612 INFO [stderr] 318 |                 into.put_i32::<BigEndian>(c.buffer_available.unwrap_or(8175)); // TODO: better defaults
Dec 02 15:40:40.612 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.614 INFO [stderr] 
Dec 02 15:40:40.614 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.614 INFO [stderr]    --> src/packet.rs:319:22
Dec 02 15:40:40.614 INFO [stderr]     |
Dec 02 15:40:40.614 INFO [stderr] 319 |                 into.put_i32::<BigEndian>(c.packet_recv_rate.unwrap_or(10_000));
Dec 02 15:40:40.614 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.614 INFO [stderr] 
Dec 02 15:40:40.614 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.614 INFO [stderr]    --> src/packet.rs:320:22
Dec 02 15:40:40.614 INFO [stderr]     |
Dec 02 15:40:40.614 INFO [stderr] 320 |                 into.put_i32::<BigEndian>(c.est_link_cap.unwrap_or(1_000));
Dec 02 15:40:40.614 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.614 INFO [stderr] 
Dec 02 15:40:40.619 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.619 INFO [stderr]    --> src/packet.rs:323:22
Dec 02 15:40:40.619 INFO [stderr]     |
Dec 02 15:40:40.619 INFO [stderr] 323 |                 into.put_i32::<BigEndian>(loss);
Dec 02 15:40:40.619 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.619 INFO [stderr] 
Dec 02 15:40:40.640 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.640 INFO [stderr]    --> src/packet.rs:507:60
Dec 02 15:40:40.640 INFO [stderr]     |
Dec 02 15:40:40.640 INFO [stderr] 507 |             let seq_number = SeqNumber(Cursor::new(first4).get_i32::<BigEndian>());
Dec 02 15:40:40.640 INFO [stderr]     |                                                            ^^^^^^^
Dec 02 15:40:40.640 INFO [stderr] 
Dec 02 15:40:40.642 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.642 INFO [stderr]    --> src/packet.rs:510:35
Dec 02 15:40:40.642 INFO [stderr]     |
Dec 02 15:40:40.642 INFO [stderr] 510 |             let second_line = buf.get_i32::<BigEndian>();
Dec 02 15:40:40.642 INFO [stderr]     |                                   ^^^^^^^
Dec 02 15:40:40.642 INFO [stderr] 
Dec 02 15:40:40.642 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.642 INFO [stderr]    --> src/packet.rs:519:33
Dec 02 15:40:40.642 INFO [stderr]     |
Dec 02 15:40:40.642 INFO [stderr] 519 |             let timestamp = buf.get_i32::<BigEndian>();
Dec 02 15:40:40.642 INFO [stderr]     |                                 ^^^^^^^
Dec 02 15:40:40.642 INFO [stderr] 
Dec 02 15:40:40.643 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.644 INFO [stderr]    --> src/packet.rs:520:44
Dec 02 15:40:40.644 INFO [stderr]     |
Dec 02 15:40:40.644 INFO [stderr] 520 |             let dest_sockid = SocketID(buf.get_i32::<BigEndian>());
Dec 02 15:40:40.644 INFO [stderr]     |                                            ^^^^^^^
Dec 02 15:40:40.644 INFO [stderr] 
Dec 02 15:40:40.644 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.644 INFO [stderr]    --> src/packet.rs:534:32
Dec 02 15:40:40.644 INFO [stderr]     |
Dec 02 15:40:40.644 INFO [stderr] 534 |             let add_info = buf.get_i32::<BigEndian>();
Dec 02 15:40:40.644 INFO [stderr]     |                                ^^^^^^^
Dec 02 15:40:40.644 INFO [stderr] 
Dec 02 15:40:40.644 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.644 INFO [stderr]    --> src/packet.rs:535:33
Dec 02 15:40:40.644 INFO [stderr]     |
Dec 02 15:40:40.644 INFO [stderr] 535 |             let timestamp = buf.get_i32::<BigEndian>();
Dec 02 15:40:40.644 INFO [stderr]     |                                 ^^^^^^^
Dec 02 15:40:40.644 INFO [stderr] 
Dec 02 15:40:40.647 INFO [stderr] warning: use of deprecated item 'bytes::Buf::get_i32': use get_i32_be or get_i32_le
Dec 02 15:40:40.647 INFO [stderr]    --> src/packet.rs:536:35
Dec 02 15:40:40.647 INFO [stderr]     |
Dec 02 15:40:40.647 INFO [stderr] 536 |             let dest_sockid = buf.get_i32::<BigEndian>();
Dec 02 15:40:40.647 INFO [stderr]     |                                   ^^^^^^^
Dec 02 15:40:40.647 INFO [stderr] 
Dec 02 15:40:40.651 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i16': use put_i16_be or put_i16_le
Dec 02 15:40:40.651 INFO [stderr]    --> src/packet.rs:555:22
Dec 02 15:40:40.651 INFO [stderr]     |
Dec 02 15:40:40.651 INFO [stderr] 555 |                 into.put_i16::<BigEndian>((i16::from(control_type.id_byte())) | (0b1 << 15));
Dec 02 15:40:40.651 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.651 INFO [stderr] 
Dec 02 15:40:40.654 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i16': use put_i16_be or put_i16_le
Dec 02 15:40:40.654 INFO [stderr]    --> src/packet.rs:558:22
Dec 02 15:40:40.654 INFO [stderr]     |
Dec 02 15:40:40.654 INFO [stderr] 558 |                 into.put_i16::<BigEndian>(0);
Dec 02 15:40:40.654 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.654 INFO [stderr] 
Dec 02 15:40:40.654 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.654 INFO [stderr]    --> src/packet.rs:561:22
Dec 02 15:40:40.654 INFO [stderr]     |
Dec 02 15:40:40.654 INFO [stderr] 561 |                 into.put_i32::<BigEndian>(control_type.additional_info());
Dec 02 15:40:40.654 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.654 INFO [stderr] 
Dec 02 15:40:40.654 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.654 INFO [stderr]    --> src/packet.rs:564:22
Dec 02 15:40:40.654 INFO [stderr]     |
Dec 02 15:40:40.654 INFO [stderr] 564 |                 into.put_i32::<BigEndian>(*timestamp);
Dec 02 15:40:40.654 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.654 INFO [stderr] 
Dec 02 15:40:40.655 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.655 INFO [stderr]    --> src/packet.rs:567:22
Dec 02 15:40:40.655 INFO [stderr]     |
Dec 02 15:40:40.655 INFO [stderr] 567 |                 into.put_i32::<BigEndian>(dest_sockid.0);
Dec 02 15:40:40.655 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.655 INFO [stderr] 
Dec 02 15:40:40.655 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.655 INFO [stderr]    --> src/packet.rs:581:22
Dec 02 15:40:40.655 INFO [stderr]     |
Dec 02 15:40:40.655 INFO [stderr] 581 |                 into.put_i32::<BigEndian>(seq_number.0);
Dec 02 15:40:40.655 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.655 INFO [stderr] 
Dec 02 15:40:40.656 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.656 INFO [stderr]    --> src/packet.rs:582:22
Dec 02 15:40:40.656 INFO [stderr]     |
Dec 02 15:40:40.656 INFO [stderr] 582 |                 into.put_i32::<BigEndian>(
Dec 02 15:40:40.656 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.656 INFO [stderr] 
Dec 02 15:40:40.659 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.659 INFO [stderr]    --> src/packet.rs:587:22
Dec 02 15:40:40.659 INFO [stderr]     |
Dec 02 15:40:40.659 INFO [stderr] 587 |                 into.put_i32::<BigEndian>(*timestamp);
Dec 02 15:40:40.659 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.659 INFO [stderr] 
Dec 02 15:40:40.659 INFO [stderr] warning: use of deprecated item 'bytes::BufMut::put_i32': use put_i32_be or put_i32_le
Dec 02 15:40:40.659 INFO [stderr]    --> src/packet.rs:588:22
Dec 02 15:40:40.659 INFO [stderr]     |
Dec 02 15:40:40.659 INFO [stderr] 588 |                 into.put_i32::<BigEndian>(dest_sockid.0);
Dec 02 15:40:40.659 INFO [stderr]     |                      ^^^^^^^
Dec 02 15:40:40.659 INFO [stderr] 
Dec 02 15:40:41.570 INFO [stderr] warning: field is never used: `local_public`
Dec 02 15:40:41.570 INFO [stderr]   --> src/pending_connection/rendezvous.rs:10:5
Dec 02 15:40:41.570 INFO [stderr]    |
Dec 02 15:40:41.570 INFO [stderr] 10 |     local_public: SocketAddr,
Dec 02 15:40:41.570 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:41.570 INFO [stderr]    |
Dec 02 15:40:41.570 INFO [stderr]    = note: #[warn(dead_code)] on by default
Dec 02 15:40:41.570 INFO [stderr] 
Dec 02 15:40:41.570 INFO [stderr] warning: field is never used: `remote_public`
Dec 02 15:40:41.570 INFO [stderr]   --> src/pending_connection/rendezvous.rs:11:5
Dec 02 15:40:41.570 INFO [stderr]    |
Dec 02 15:40:41.570 INFO [stderr] 11 |     remote_public: SocketAddr,
Dec 02 15:40:41.570 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:41.570 INFO [stderr] 
Dec 02 15:40:41.570 INFO [stderr] warning: field is never used: `sock`
Dec 02 15:40:41.570 INFO [stderr]   --> src/pending_connection/rendezvous.rs:12:5
Dec 02 15:40:41.570 INFO [stderr]    |
Dec 02 15:40:41.570 INFO [stderr] 12 |     sock: T,
Dec 02 15:40:41.570 INFO [stderr]    |     ^^^^^^^
Dec 02 15:40:41.570 INFO [stderr] 
Dec 02 15:40:41.663 INFO [stderr] warning: unused import: `DefaultReceiverCongestionCtrl`
Dec 02 15:40:41.663 INFO [stderr]   --> tests/tests.rs:22:31
Dec 02 15:40:41.663 INFO [stderr]    |
Dec 02 15:40:41.663 INFO [stderr] 22 | use srt::{ConnectionSettings, DefaultReceiverCongestionCtrl, DefaultSenderCongestionCtrl,
Dec 02 15:40:41.663 INFO [stderr]    |                               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:41.663 INFO [stderr]    |
Dec 02 15:40:41.663 INFO [stderr]    = note: #[warn(unused_imports)] on by default
Dec 02 15:40:41.663 INFO [stderr] 
Dec 02 15:40:41.663 INFO [stderr] warning: unused import: `tokio::executor::current_thread`
Dec 02 15:40:41.663 INFO [stderr]   --> tests/tests.rs:25:5
Dec 02 15:40:41.663 INFO [stderr]    |
Dec 02 15:40:41.663 INFO [stderr] 25 | use tokio::executor::current_thread;
Dec 02 15:40:41.663 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:41.663 INFO [stderr] 
Dec 02 15:40:41.663 INFO [stderr] warning: unnecessary parentheses around function argument
Dec 02 15:40:41.663 INFO [stderr]    --> tests/tests.rs:216:35
Dec 02 15:40:41.663 INFO [stderr]     |
Dec 02 15:40:41.663 INFO [stderr] 216 |     let counting_stream = iter_ok((init_seq_num as u64..(init_seq_num + iters)))
Dec 02 15:40:41.663 INFO [stderr]     |                                   ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ help: remove these parentheses
Dec 02 15:40:41.663 INFO [stderr]     |
Dec 02 15:40:41.663 INFO [stderr]     = note: #[warn(unused_parens)] on by default
Dec 02 15:40:41.663 INFO [stderr] 
Dec 02 15:40:41.703 INFO [stderr] warning: use of deprecated item 'tokio::executor::current_thread': use tokio-current-thread crate or functions in tokio::runtime::current_thread instead
Dec 02 15:40:41.703 INFO [stderr]   --> tests/tests.rs:25:5
Dec 02 15:40:41.703 INFO [stderr]    |
Dec 02 15:40:41.703 INFO [stderr] 25 | use tokio::executor::current_thread;
Dec 02 15:40:41.703 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:41.703 INFO [stderr]    |
Dec 02 15:40:41.703 INFO [stderr]    = note: #[warn(deprecated)] on by default
Dec 02 15:40:41.703 INFO [stderr] 
Dec 02 15:40:42.095 INFO [stderr] warning: constant `init_seq_num` should have an upper case name such as `INIT_SEQ_NUM`
Dec 02 15:40:42.095 INFO [stderr]    --> tests/tests.rs:212:5
Dec 02 15:40:42.095 INFO [stderr]     |
Dec 02 15:40:42.095 INFO [stderr] 212 |     const init_seq_num: u64 = 812731;
Dec 02 15:40:42.095 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:42.095 INFO [stderr] 
Dec 02 15:40:42.095 INFO [stderr] warning: constant `iters` should have an upper case name such as `ITERS`
Dec 02 15:40:42.095 INFO [stderr]    --> tests/tests.rs:213:5
Dec 02 15:40:42.095 INFO [stderr]     |
Dec 02 15:40:42.095 INFO [stderr] 213 |     const iters: u64 = 1_000_000;
Dec 02 15:40:42.095 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:42.095 INFO [stderr] 
Dec 02 15:40:42.095 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 02 15:40:42.095 INFO [stderr]    --> tests/tests.rs:253:9
Dec 02 15:40:42.095 INFO [stderr]     |
Dec 02 15:40:42.095 INFO [stderr] 253 | /         counting_stream
Dec 02 15:40:42.095 INFO [stderr] 254 | |             .forward(sender)
Dec 02 15:40:42.095 INFO [stderr] 255 | |             .map_err(|e: Error| panic!("{:?}", e))
Dec 02 15:40:42.095 INFO [stderr] 256 | |             .map(|_| ())
Dec 02 15:40:42.095 INFO [stderr] 257 | |             .wait();
Dec 02 15:40:42.095 INFO [stderr]     | |____________________^
Dec 02 15:40:42.095 INFO [stderr]     |
Dec 02 15:40:42.095 INFO [stderr]     = note: #[warn(unused_must_use)] on by default
Dec 02 15:40:42.095 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 02 15:40:42.095 INFO [stderr] 
Dec 02 15:40:42.095 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 02 15:40:42.095 INFO [stderr]    --> tests/tests.rs:261:9
Dec 02 15:40:42.095 INFO [stderr]     |
Dec 02 15:40:42.095 INFO [stderr] 261 | /         recvr
Dec 02 15:40:42.095 INFO [stderr] 262 | |             .forward(CounterChecker {
Dec 02 15:40:42.095 INFO [stderr] 263 | |                 current: init_seq_num,
Dec 02 15:40:42.095 INFO [stderr] 264 | |             })
Dec 02 15:40:42.095 INFO [stderr] 265 | |             .map_err(|e| panic!(e))
Dec 02 15:40:42.095 INFO [stderr] 266 | |             .map(move |(_, c)| assert_eq!(c.current, init_seq_num + iters))
Dec 02 15:40:42.095 INFO [stderr] 267 | |             .wait();
Dec 02 15:40:42.095 INFO [stderr]     | |____________________^
Dec 02 15:40:42.095 INFO [stderr]     |
Dec 02 15:40:42.095 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 02 15:40:42.095 INFO [stderr] 
Dec 02 15:40:42.095 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 02 15:40:42.095 INFO [stderr]    --> tests/tests.rs:270:5
Dec 02 15:40:42.095 INFO [stderr]     |
Dec 02 15:40:42.095 INFO [stderr] 270 |     t1.join();
Dec 02 15:40:42.095 INFO [stderr]     |     ^^^^^^^^^^
Dec 02 15:40:42.095 INFO [stderr]     |
Dec 02 15:40:42.095 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 02 15:40:42.095 INFO [stderr] 
Dec 02 15:40:42.095 INFO [stderr] warning: unused `std::result::Result` that must be used
Dec 02 15:40:42.095 INFO [stderr]    --> tests/tests.rs:271:5
Dec 02 15:40:42.095 INFO [stderr]     |
Dec 02 15:40:42.095 INFO [stderr] 271 |     t2.join();
Dec 02 15:40:42.095 INFO [stderr]     |     ^^^^^^^^^^
Dec 02 15:40:42.095 INFO [stderr]     |
Dec 02 15:40:42.095 INFO [stderr]     = note: this `Result` may be an `Err` variant, which should be handled
Dec 02 15:40:42.095 INFO [stderr] 
Dec 02 15:40:42.798 INFO [stderr] warning: use of deprecated item 'tokio::executor::current_thread': use tokio-current-thread crate or functions in tokio::runtime::current_thread instead
Dec 02 15:40:42.798 INFO [stderr]   --> src/bin.rs:18:13
Dec 02 15:40:42.804 INFO [stderr]    |
Dec 02 15:40:42.804 INFO [stderr] 18 | use tokio::{executor::current_thread, net::{UdpFramed, UdpSocket}};
Dec 02 15:40:42.804 INFO [stderr]    |             ^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:42.804 INFO [stderr]    |
Dec 02 15:40:42.804 INFO [stderr]    = note: #[warn(deprecated)] on by default
Dec 02 15:40:42.804 INFO [stderr] 
Dec 02 15:40:42.804 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::BytesCodec': Moved to tokio-codec
Dec 02 15:40:42.804 INFO [stderr]   --> src/bin.rs:19:5
Dec 02 15:40:42.804 INFO [stderr]    |
Dec 02 15:40:42.804 INFO [stderr] 19 | use tokio_io::codec::BytesCodec;
Dec 02 15:40:42.804 INFO [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:42.804 INFO [stderr] 
Dec 02 15:40:42.804 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::BytesCodec': Moved to tokio-codec
Dec 02 15:40:42.804 INFO [stderr]   --> src/bin.rs:89:25
Dec 02 15:40:42.804 INFO [stderr]    |
Dec 02 15:40:42.804 INFO [stderr] 89 |                         BytesCodec::new(),
Dec 02 15:40:42.804 INFO [stderr]    |                         ^^^^^^^^^^^^^^^
Dec 02 15:40:42.804 INFO [stderr] 
Dec 02 15:40:42.804 INFO [stderr] warning: use of deprecated item 'tokio_io::codec::BytesCodec': Moved to tokio-codec
Dec 02 15:40:42.804 INFO [stderr]    --> src/bin.rs:142:21
Dec 02 15:40:42.804 INFO [stderr]     |
Dec 02 15:40:42.804 INFO [stderr] 142 |                     BytesCodec::new(),
Dec 02 15:40:42.804 INFO [stderr]     |                     ^^^^^^^^^^^^^^^
Dec 02 15:40:42.806 INFO [stderr] 
Dec 02 15:40:42.806 INFO [stderr] warning: use of deprecated item 'tokio::executor::current_thread::run': use block_on_all instead
Dec 02 15:40:42.806 INFO [stderr]    --> src/bin.rs:159:5
Dec 02 15:40:42.806 INFO [stderr]     |
Dec 02 15:40:42.806 INFO [stderr] 159 |     current_thread::run(|_| {
Dec 02 15:40:42.806 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^
Dec 02 15:40:42.806 INFO [stderr] 
Dec 02 15:40:46.391 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.82s
Dec 02 15:40:46.403 INFO [stderr] su: No module specific data is present
Dec 02 15:40:47.031 INFO running `"docker" "inspect" "3e713e53ee55da1603721e97f3e7e3de91705ff63160dfd0e874e5f38443ec4d"`
Dec 02 15:40:47.249 INFO running `"docker" "rm" "-f" "3e713e53ee55da1603721e97f3e7e3de91705ff63160dfd0e874e5f38443ec4d"`
Dec 02 15:40:47.431 INFO [stdout] 3e713e53ee55da1603721e97f3e7e3de91705ff63160dfd0e874e5f38443ec4d
Dec 02 15:40:47.433 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-4/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-4/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 02 15:40:48.085 INFO [stdout] d64c72c5b9a18978bd3b5f9998e671b181c4a8cb2f39972fb4696793ce55c9a9
Dec 02 15:40:48.095 INFO running `"docker" "start" "-a" "d64c72c5b9a18978bd3b5f9998e671b181c4a8cb2f39972fb4696793ce55c9a9"`
Dec 02 15:40:49.258 INFO [stderr] usermod: no changes
Dec 02 15:40:49.422 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.13s
Dec 02 15:40:49.434 INFO [stderr]      Running /target/debug/deps/srt-d0759fde41e20d52
Dec 02 15:40:49.435 INFO [stdout] 
Dec 02 15:40:49.435 INFO [stdout] running 5 tests
Dec 02 15:40:49.436 INFO [stdout] test seq_number::seq_num_test ... ok
Dec 02 15:40:49.436 INFO [stdout] test packet::packet_location_to_i32_test ... ok
Dec 02 15:40:49.436 INFO [stdout] test packet::packet_location_from_i32_test ... ok
Dec 02 15:40:49.436 INFO [stdout] test packet::handshake_ser_des_test ... ok
Dec 02 15:40:49.436 INFO [stdout] test loss_compression::loss_compression_test ... ok
Dec 02 15:40:49.436 INFO [stdout] 
Dec 02 15:40:49.436 INFO [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 15:40:49.436 INFO [stdout] 
Dec 02 15:40:49.442 INFO [stderr]      Running /target/debug/deps/stransmit_rs-b6daff38f5084f3b
Dec 02 15:40:49.456 INFO [stdout] 
Dec 02 15:40:49.457 INFO [stdout] running 0 tests
Dec 02 15:40:49.457 INFO [stdout] 
Dec 02 15:40:49.457 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 15:40:49.457 INFO [stdout] 
Dec 02 15:40:49.459 INFO [stderr]      Running /target/debug/deps/tests-cc36004f07e24b8b
Dec 02 15:40:49.467 INFO [stdout] 
Dec 02 15:40:49.467 INFO [stdout] running 1 test
Dec 02 15:40:49.471 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812731
Dec 02 15:40:49.471 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812732
Dec 02 15:40:49.471 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812733
Dec 02 15:40:49.471 INFO [stdout] 2018-12-02 15:40:49 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812733), message_loc: Only, in_order_delivery: false, message_number: 2, timestamp: 4536, dest_sockid: SocketID(81), payload: b"812733" }, V4(0.0.0.0:0))
Dec 02 15:40:49.471 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812734
Dec 02 15:40:49.471 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812730, buffer=[812731]
Dec 02 15:40:49.471 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812730, buffer=[812732, 812731]
Dec 02 15:40:49.471 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812733)]
Dec 02 15:40:49.471 INFO [stdout] 2018-12-02 15:40:49 INFO  [tests] Dropping packet: (Control { timestamp: 6123, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812733] }) }, V4(0.0.0.0:0))
Dec 02 15:40:49.471 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812730, buffer=[812734, 812732, 812731]
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812731)
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812732)
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812733), lr_ack_acked=SeqNumber(812731)
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812735
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812735), message_loc: Only, in_order_delivery: false, message_number: 4, timestamp: 22335, dest_sockid: SocketID(81), payload: b"812735" }, V4(0.0.0.0:0))
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Got packet: Control { timestamp: 22120, dest_sockid: SocketID(13), control_type: Ack(1, AckControlInfo { ack_number: SeqNumber(812733), rtt: Some(10000), rtt_variance: Some(1000), buffer_available: None, packet_recv_rate: Some(0), est_link_cap: Some(0) }) }
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::default_congestion_ctrl::sender] New window size: 16
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812736
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812735)]
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812736, 812734]
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Got packet: Control { timestamp: 23424, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812735] }) }
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Loss list=[812735]
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812737, 812736, 812734]
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812735)
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812737, 812736, 812735, 812734]
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812738
Dec 02 15:40:49.491 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812738, 812737, 812736, 812735, 812734]
Dec 02 15:40:49.494 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812739
Dec 02 15:40:49.495 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812739, 812738, 812737, 812736, 812735, 812734]
Dec 02 15:40:49.495 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812740
Dec 02 15:40:49.495 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812740, 812739, 812738, 812737, 812736, 812735, 812734]
Dec 02 15:40:49.495 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812741
Dec 02 15:40:49.495 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812741, 812740, 812739, 812738, 812737, 812736, 812735, 812734]
Dec 02 15:40:49.499 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812742
Dec 02 15:40:49.499 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812742, 812741, 812740, 812739, 812738, 812737, 812736, 812735, 812734]
Dec 02 15:40:49.499 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812743
Dec 02 15:40:49.499 INFO [stdout] 2018-12-02 15:40:49 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812743), message_loc: Only, in_order_delivery: false, message_number: 12, timestamp: 31902, dest_sockid: SocketID(81), payload: b"812743" }, V4(0.0.0.0:0))
Dec 02 15:40:49.499 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812744
Dec 02 15:40:49.499 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812743)]
Dec 02 15:40:49.499 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812744, 812742, 812741, 812740, 812739, 812738, 812737, 812736, 812735, 812734]
Dec 02 15:40:49.499 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Got packet: Control { timestamp: 33146, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812743] }) }
Dec 02 15:40:49.499 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Loss list=[812743]
Dec 02 15:40:49.503 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812743)
Dec 02 15:40:49.503 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812744, 812743, 812742, 812741, 812740, 812739, 812738, 812737, 812736, 812735, 812734]
Dec 02 15:40:49.503 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812745
Dec 02 15:40:49.503 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812745, 812744, 812743, 812742, 812741, 812740, 812739, 812738, 812737, 812736, 812735, 812734]
Dec 02 15:40:49.503 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812746
Dec 02 15:40:49.503 INFO [stdout] 2018-12-02 15:40:49 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812746), message_loc: Only, in_order_delivery: false, message_number: 15, timestamp: 37330, dest_sockid: SocketID(81), payload: b"812746" }, V4(0.0.0.0:0))
Dec 02 15:40:49.507 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812747
Dec 02 15:40:49.507 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812746)]
Dec 02 15:40:49.507 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812747, 812745, 812744, 812743, 812742, 812741, 812740, 812739, 812738, 812737, 812736, 812735, 812734]
Dec 02 15:40:49.507 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Got packet: Control { timestamp: 38710, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812746] }) }
Dec 02 15:40:49.507 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Loss list=[812746]
Dec 02 15:40:49.507 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812746)
Dec 02 15:40:49.507 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812747, 812746, 812745, 812744, 812743, 812742, 812741, 812740, 812739, 812738, 812737, 812736, 812735, 812734]
Dec 02 15:40:49.507 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812748
Dec 02 15:40:49.507 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812748, 812747, 812746, 812745, 812744, 812743, 812742, 812741, 812740, 812739, 812738, 812737, 812736, 812735, 812734]
Dec 02 15:40:49.511 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812749
Dec 02 15:40:49.511 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812749, 812748, 812747, 812746, 812745, 812744, 812743, 812742, 812741, 812740, 812739, 812738, 812737, 812736, 812735, 812734]
Dec 02 15:40:49.534 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812733] [SeqNumber(812733)]
Dec 02 15:40:49.534 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812733)]
Dec 02 15:40:49.534 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Got packet: Control { timestamp: 68837, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812733] }) }
Dec 02 15:40:49.534 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Loss list=[812733]
Dec 02 15:40:49.582 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812733] [SeqNumber(812733)]
Dec 02 15:40:49.582 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812733)]
Dec 02 15:40:49.627 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812733] [SeqNumber(812733)]
Dec 02 15:40:49.627 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812733)]
Dec 02 15:40:49.673 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812733] [SeqNumber(812733)]
Dec 02 15:40:49.673 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812733)]
Dec 02 15:40:49.763 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Got packet: Control { timestamp: 114946, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812733] }) }
Dec 02 15:40:49.763 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Loss list=[812733, 812733]
Dec 02 15:40:49.763 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Got packet: Control { timestamp: 161056, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812733] }) }
Dec 02 15:40:49.763 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Loss list=[812733, 812733, 812733]
Dec 02 15:40:49.763 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Got packet: Control { timestamp: 207170, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812733] }) }
Dec 02 15:40:49.763 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Loss list=[812733, 812733, 812733, 812733]
Dec 02 15:40:49.763 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812733)
Dec 02 15:40:49.763 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812732, buffer=[812749, 812748, 812747, 812746, 812745, 812744, 812743, 812742, 812741, 812740, 812739, 812738, 812737, 812736, 812735, 812734, 812733]
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812733)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812734)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812735)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812736)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812737)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812738)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812739)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812740)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812741)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812742)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812743)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812744)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812745)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812746)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812747)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812748)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812749)
Dec 02 15:40:49.767 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812750), lr_ack_acked=SeqNumber(812733)
Dec 02 15:40:49.810 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812750), lr_ack_acked=SeqNumber(812733)
Dec 02 15:40:49.810 INFO [stdout] 2018-12-02 15:40:49 INFO  [tests] Dropping packet: (Control { timestamp: 345571, dest_sockid: SocketID(13), control_type: Ack(3, AckControlInfo { ack_number: SeqNumber(812750), rtt: Some(8808), rtt_variance: Some(883), buffer_available: None, packet_recv_rate: Some(32063), est_link_cap: Some(0) }) }, V4(0.0.0.0:0))
Dec 02 15:40:49.859 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Got packet: Control { timestamp: 299630, dest_sockid: SocketID(13), control_type: Ack(2, AckControlInfo { ack_number: SeqNumber(812750), rtt: Some(8808), rtt_variance: Some(883), buffer_available: None, packet_recv_rate: Some(32063), est_link_cap: Some(0) }) }
Dec 02 15:40:49.859 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::default_congestion_ctrl::sender] New window size: 16
Dec 02 15:40:49.859 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812750
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812750), lr_ack_acked=SeqNumber(812733)
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812751
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812749, buffer=[812750]
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Got packet: Control { timestamp: 394104, dest_sockid: SocketID(13), control_type: Ack(4, AckControlInfo { ack_number: SeqNumber(812750), rtt: Some(8808), rtt_variance: Some(883), buffer_available: None, packet_recv_rate: Some(32063), est_link_cap: Some(0) }) }
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [tests] Dropping packet: (Control { timestamp: 398347, dest_sockid: SocketID(81), control_type: Ack2(4) }, V4(0.0.0.0:0))
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::default_congestion_ctrl::sender] New window size: 91
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812752
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812750)
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812750, buffer=[812751]
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812751)
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812751, buffer=[812752]
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812752)
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812752, buffer=[812753]
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812754
Dec 02 15:40:49.867 INFO [stdout] 2018-12-02 15:40:49 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812754), message_loc: Only, in_order_delivery: false, message_number: 23, timestamp: 402218, dest_sockid: SocketID(81), payload: b"812754" }, V4(0.0.0.0:0))
Dec 02 15:40:49.875 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812755
Dec 02 15:40:49.875 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Releasing SeqNumber(812753)
Dec 02 15:40:49.875 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812754)]
Dec 02 15:40:49.875 INFO [stdout] 2018-12-02 15:40:49 INFO  [tests] Dropping packet: (Control { timestamp: 406183, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812754] }) }, V4(0.0.0.0:0))
Dec 02 15:40:49.875 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812753, buffer=[812755]
Dec 02 15:40:49.875 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812756
Dec 02 15:40:49.875 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812753, buffer=[812756, 812755]
Dec 02 15:40:49.879 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::sender] Sending packet: 812757
Dec 02 15:40:49.879 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] lr=812753, buffer=[812757, 812756, 812755]
Dec 02 15:40:49.983 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812754), lr_ack_acked=SeqNumber(812750)
Dec 02 15:40:49.983 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812754] [SeqNumber(812754)]
Dec 02 15:40:49.983 INFO [stdout] 2018-12-02 15:40:49 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812754)]
Dec 02 15:40:50.041 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 506411, dest_sockid: SocketID(13), control_type: Ack(5, AckControlInfo { ack_number: SeqNumber(812754), rtt: Some(19519), rtt_variance: Some(24067), buffer_available: None, packet_recv_rate: Some(273609), est_link_cap: Some(0) }) }
Dec 02 15:40:50.041 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::default_congestion_ctrl::sender] New window size: 237
Dec 02 15:40:50.041 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 506488, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812754] }) }
Dec 02 15:40:50.041 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Loss list=[812754]
Dec 02 15:40:50.041 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812754)
Dec 02 15:40:50.041 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812753, buffer=[812757, 812756, 812755, 812754]
Dec 02 15:40:50.043 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812758
Dec 02 15:40:50.043 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812754)
Dec 02 15:40:50.043 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812755)
Dec 02 15:40:50.043 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812756)
Dec 02 15:40:50.043 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812757)
Dec 02 15:40:50.043 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812757, buffer=[812758]
Dec 02 15:40:50.046 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812759
Dec 02 15:40:50.046 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812759), message_loc: Only, in_order_delivery: false, message_number: 28, timestamp: 581285, dest_sockid: SocketID(81), payload: b"812759" }, V4(0.0.0.0:0))
Dec 02 15:40:50.050 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812760
Dec 02 15:40:50.050 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812758)
Dec 02 15:40:50.050 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812759)]
Dec 02 15:40:50.050 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812758, buffer=[812760]
Dec 02 15:40:50.050 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 585110, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812759] }) }
Dec 02 15:40:50.050 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Loss list=[812759]
Dec 02 15:40:50.077 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812759)
Dec 02 15:40:50.078 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812758, buffer=[812760, 812759]
Dec 02 15:40:50.083 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812761
Dec 02 15:40:50.083 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812761), message_loc: Only, in_order_delivery: false, message_number: 30, timestamp: 618004, dest_sockid: SocketID(81), payload: b"812761" }, V4(0.0.0.0:0))
Dec 02 15:40:50.093 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812762
Dec 02 15:40:50.093 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812759)
Dec 02 15:40:50.093 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812760)
Dec 02 15:40:50.093 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812761)]
Dec 02 15:40:50.093 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812760, buffer=[812762]
Dec 02 15:40:50.095 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 627906, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812761] }) }
Dec 02 15:40:50.095 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Loss list=[812761]
Dec 02 15:40:50.096 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812761)
Dec 02 15:40:50.097 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812760, buffer=[812762, 812761]
Dec 02 15:40:50.099 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812763
Dec 02 15:40:50.099 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812761)
Dec 02 15:40:50.100 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812762)
Dec 02 15:40:50.100 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812762, buffer=[812763]
Dec 02 15:40:50.102 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812764
Dec 02 15:40:50.103 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812763)
Dec 02 15:40:50.103 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812763, buffer=[812764]
Dec 02 15:40:50.106 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812765
Dec 02 15:40:50.106 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812764)
Dec 02 15:40:50.106 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812764, buffer=[812765]
Dec 02 15:40:50.109 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812766
Dec 02 15:40:50.109 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812765)
Dec 02 15:40:50.109 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812765, buffer=[812766]
Dec 02 15:40:50.112 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812767
Dec 02 15:40:50.112 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812766)
Dec 02 15:40:50.112 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812766, buffer=[812767]
Dec 02 15:40:50.116 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812768
Dec 02 15:40:50.116 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812767)
Dec 02 15:40:50.116 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812767, buffer=[812768]
Dec 02 15:40:50.116 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812768)
Dec 02 15:40:50.116 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812768, buffer=[812769]
Dec 02 15:40:50.123 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812770
Dec 02 15:40:50.123 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812769)
Dec 02 15:40:50.123 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812769, buffer=[812770]
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812771
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812770)
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812770, buffer=[812771]
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812772
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812771)
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812771, buffer=[812772]
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812773
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812772)
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812772, buffer=[812773]
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812774
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812773)
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812773, buffer=[812774]
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812775
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812774)
Dec 02 15:40:50.136 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812774, buffer=[812775]
Dec 02 15:40:50.146 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812776
Dec 02 15:40:50.146 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812776), message_loc: Only, in_order_delivery: false, message_number: 45, timestamp: 673503, dest_sockid: SocketID(81), payload: b"812776" }, V4(0.0.0.0:0))
Dec 02 15:40:50.146 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812777
Dec 02 15:40:50.146 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812775)
Dec 02 15:40:50.146 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812776)]
Dec 02 15:40:50.146 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Control { timestamp: 676821, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812776] }) }, V4(0.0.0.0:0))
Dec 02 15:40:50.146 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812777]
Dec 02 15:40:50.146 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812778
Dec 02 15:40:50.150 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812778, 812777]
Dec 02 15:40:50.150 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812779
Dec 02 15:40:50.151 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812779, 812778, 812777]
Dec 02 15:40:50.187 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812776), lr_ack_acked=SeqNumber(812754)
Dec 02 15:40:50.196 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 719309, dest_sockid: SocketID(13), control_type: Ack(6, AckControlInfo { ack_number: SeqNumber(812776), rtt: Some(25824), rtt_variance: Some(29524), buffer_available: None, packet_recv_rate: Some(657527), est_link_cap: Some(0) }) }
Dec 02 15:40:50.197 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:40:50.197 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812780
Dec 02 15:40:50.198 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812780, 812779, 812778, 812777]
Dec 02 15:40:50.208 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812781
Dec 02 15:40:50.208 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812781), message_loc: Only, in_order_delivery: false, message_number: 50, timestamp: 743694, dest_sockid: SocketID(81), payload: b"812781" }, V4(0.0.0.0:0))
Dec 02 15:40:50.212 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812782
Dec 02 15:40:50.212 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812781)]
Dec 02 15:40:50.212 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812782, 812780, 812779, 812778, 812777]
Dec 02 15:40:50.212 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 747135, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812781] }) }
Dec 02 15:40:50.212 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Loss list=[812781]
Dec 02 15:40:50.224 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812781)
Dec 02 15:40:50.224 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812782, 812781, 812780, 812779, 812778, 812777]
Dec 02 15:40:50.231 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812783
Dec 02 15:40:50.231 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812783, 812782, 812781, 812780, 812779, 812778, 812777]
Dec 02 15:40:50.231 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812784
Dec 02 15:40:50.231 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812784), message_loc: Only, in_order_delivery: false, message_number: 53, timestamp: 765428, dest_sockid: SocketID(81), payload: b"812784" }, V4(0.0.0.0:0))
Dec 02 15:40:50.231 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812784)]
Dec 02 15:40:50.231 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Control { timestamp: 765486, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812784] }) }, V4(0.0.0.0:0))
Dec 02 15:40:50.231 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812785, 812783, 812782, 812781, 812780, 812779, 812778, 812777]
Dec 02 15:40:50.235 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812786
Dec 02 15:40:50.235 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812786, 812785, 812783, 812782, 812781, 812780, 812779, 812778, 812777]
Dec 02 15:40:50.321 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812787
Dec 02 15:40:50.327 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812787, 812786, 812785, 812783, 812782, 812781, 812780, 812779, 812778, 812777]
Dec 02 15:40:50.327 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812788
Dec 02 15:40:50.327 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812788, 812787, 812786, 812785, 812783, 812782, 812781, 812780, 812779, 812778, 812777]
Dec 02 15:40:50.329 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812789
Dec 02 15:40:50.329 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812789, 812788, 812787, 812786, 812785, 812783, 812782, 812781, 812780, 812779, 812778, 812777]
Dec 02 15:40:50.332 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812776, 812784] [SeqNumber(812776), SeqNumber(812784)]
Dec 02 15:40:50.332 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812776), SeqNumber(812784)]
Dec 02 15:40:50.332 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 866716, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812776, 812784] }) }
Dec 02 15:40:50.332 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Loss list=[812776, 812784]
Dec 02 15:40:50.332 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812776)
Dec 02 15:40:50.333 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812775, buffer=[812789, 812788, 812787, 812786, 812785, 812783, 812782, 812781, 812780, 812779, 812778, 812777, 812776]
Dec 02 15:40:50.338 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812784)
Dec 02 15:40:50.338 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812784), message_loc: Only, in_order_delivery: false, message_number: 53, timestamp: 765428, dest_sockid: SocketID(81), payload: b"812784" }, V4(0.0.0.0:0))
Dec 02 15:40:50.344 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812790
Dec 02 15:40:50.347 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812776)
Dec 02 15:40:50.347 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812777)
Dec 02 15:40:50.347 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812778)
Dec 02 15:40:50.347 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812779)
Dec 02 15:40:50.347 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812780)
Dec 02 15:40:50.347 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812781)
Dec 02 15:40:50.347 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812782)
Dec 02 15:40:50.347 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Releasing SeqNumber(812783)
Dec 02 15:40:50.347 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.350 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812791
Dec 02 15:40:50.350 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.353 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812792
Dec 02 15:40:50.353 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.363 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812793
Dec 02 15:40:50.363 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.363 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812794
Dec 02 15:40:50.363 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.373 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812795
Dec 02 15:40:50.373 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812795), message_loc: Only, in_order_delivery: false, message_number: 64, timestamp: 902329, dest_sockid: SocketID(81), payload: b"812795" }, V4(0.0.0.0:0))
Dec 02 15:40:50.375 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812796
Dec 02 15:40:50.375 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812795)]
Dec 02 15:40:50.375 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812796, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.375 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 909768, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812795] }) }
Dec 02 15:40:50.375 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Loss list=[812795]
Dec 02 15:40:50.380 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812795)
Dec 02 15:40:50.381 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.387 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812797
Dec 02 15:40:50.387 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.391 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812798
Dec 02 15:40:50.391 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.395 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812799
Dec 02 15:40:50.395 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812799), message_loc: Only, in_order_delivery: false, message_number: 68, timestamp: 929540, dest_sockid: SocketID(81), payload: b"812799" }, V4(0.0.0.0:0))
Dec 02 15:40:50.403 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812800
Dec 02 15:40:50.403 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812799)]
Dec 02 15:40:50.403 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Control { timestamp: 934182, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812799] }) }, V4(0.0.0.0:0))
Dec 02 15:40:50.403 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.403 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.407 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812802
Dec 02 15:40:50.407 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812802), message_loc: Only, in_order_delivery: false, message_number: 71, timestamp: 938750, dest_sockid: SocketID(81), payload: b"812802" }, V4(0.0.0.0:0))
Dec 02 15:40:50.411 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812803
Dec 02 15:40:50.411 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812802)]
Dec 02 15:40:50.411 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812803, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.411 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 943410, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812802] }) }
Dec 02 15:40:50.411 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Loss list=[812802]
Dec 02 15:40:50.415 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812802)
Dec 02 15:40:50.419 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.421 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812804
Dec 02 15:40:50.422 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.427 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812805
Dec 02 15:40:50.427 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.436 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812806
Dec 02 15:40:50.437 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.443 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812807
Dec 02 15:40:50.443 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.447 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812808
Dec 02 15:40:50.447 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.453 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812809
Dec 02 15:40:50.453 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.458 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812810
Dec 02 15:40:50.460 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.464 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812811
Dec 02 15:40:50.465 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.471 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812784), lr_ack_acked=SeqNumber(812776)
Dec 02 15:40:50.471 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812784, 812799] [SeqNumber(812784), SeqNumber(812799)]
Dec 02 15:40:50.471 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812784), SeqNumber(812799)]
Dec 02 15:40:50.471 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 1000154, dest_sockid: SocketID(13), control_type: Ack(7, AckControlInfo { ack_number: SeqNumber(812784), rtt: Some(24336), rtt_variance: Some(26042), buffer_available: None, packet_recv_rate: Some(953786), est_link_cap: Some(0) }) }
Dec 02 15:40:50.471 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:40:50.471 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 1000211, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812784, 812799] }) }
Dec 02 15:40:50.471 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Loss list=[812784, 812799]
Dec 02 15:40:50.471 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812784)
Dec 02 15:40:50.471 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812784), message_loc: Only, in_order_delivery: false, message_number: 53, timestamp: 765428, dest_sockid: SocketID(81), payload: b"812784" }, V4(0.0.0.0:0))
Dec 02 15:40:50.475 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812799)
Dec 02 15:40:50.475 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812799), message_loc: Only, in_order_delivery: false, message_number: 68, timestamp: 929540, dest_sockid: SocketID(81), payload: b"812799" }, V4(0.0.0.0:0))
Dec 02 15:40:50.483 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812812
Dec 02 15:40:50.483 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812812), message_loc: Only, in_order_delivery: false, message_number: 81, timestamp: 1015866, dest_sockid: SocketID(81), payload: b"812812" }, V4(0.0.0.0:0))
Dec 02 15:40:50.489 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812813
Dec 02 15:40:50.489 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812812)]
Dec 02 15:40:50.489 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Control { timestamp: 1021717, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812812] }) }, V4(0.0.0.0:0))
Dec 02 15:40:50.489 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.557 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812814
Dec 02 15:40:50.557 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.559 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812815
Dec 02 15:40:50.563 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.563 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812816
Dec 02 15:40:50.564 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812816, 812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.564 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812817, 812816, 812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.575 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812818
Dec 02 15:40:50.575 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812818, 812817, 812816, 812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.581 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812819
Dec 02 15:40:50.581 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812819, 812818, 812817, 812816, 812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.586 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812820
Dec 02 15:40:50.587 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812784, 812799, 812812] [SeqNumber(812784), SeqNumber(812799), SeqNumber(812812)]
Dec 02 15:40:50.587 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812784), SeqNumber(812799), SeqNumber(812812)]
Dec 02 15:40:50.587 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812820, 812819, 812818, 812817, 812816, 812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.587 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 1122086, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812784, 812799, 812812] }) }
Dec 02 15:40:50.589 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Loss list=[812784, 812799, 812812]
Dec 02 15:40:50.598 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812784)
Dec 02 15:40:50.598 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812784), message_loc: Only, in_order_delivery: false, message_number: 53, timestamp: 765428, dest_sockid: SocketID(81), payload: b"812784" }, V4(0.0.0.0:0))
Dec 02 15:40:50.610 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812799)
Dec 02 15:40:50.611 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812820, 812819, 812818, 812817, 812816, 812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.611 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812812)
Dec 02 15:40:50.611 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812812), message_loc: Only, in_order_delivery: false, message_number: 81, timestamp: 1015866, dest_sockid: SocketID(81), payload: b"812812" }, V4(0.0.0.0:0))
Dec 02 15:40:50.619 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812821
Dec 02 15:40:50.619 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812821, 812820, 812819, 812818, 812817, 812816, 812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.626 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812822
Dec 02 15:40:50.627 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812822, 812821, 812820, 812819, 812818, 812817, 812816, 812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.650 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812823
Dec 02 15:40:50.651 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812823, 812822, 812821, 812820, 812819, 812818, 812817, 812816, 812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.651 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812824
Dec 02 15:40:50.662 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812824, 812823, 812822, 812821, 812820, 812819, 812818, 812817, 812816, 812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.662 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812825
Dec 02 15:40:50.669 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812825, 812824, 812823, 812822, 812821, 812820, 812819, 812818, 812817, 812816, 812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785]
Dec 02 15:40:50.669 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet: 812826
Dec 02 15:40:50.669 INFO [stdout] 2018-12-02 15:40:50 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812826), message_loc: Only, in_order_delivery: false, message_number: 95, timestamp: 1202618, dest_sockid: SocketID(81), payload: b"812826" }, V4(0.0.0.0:0))
Dec 02 15:40:50.711 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812784, 812812] [SeqNumber(812784), SeqNumber(812812)]
Dec 02 15:40:50.711 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812784), SeqNumber(812812)]
Dec 02 15:40:50.835 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812784, 812812] [SeqNumber(812812)]
Dec 02 15:40:50.835 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812812)]
Dec 02 15:40:50.951 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812784, 812812] [SeqNumber(812784), SeqNumber(812812)]
Dec 02 15:40:50.951 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812784), SeqNumber(812812)]
Dec 02 15:40:50.979 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 1243137, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812784, 812812] }) }
Dec 02 15:40:50.979 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Loss list=[812784, 812812]
Dec 02 15:40:50.979 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 1364415, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812812] }) }
Dec 02 15:40:50.979 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Loss list=[812784, 812812, 812812]
Dec 02 15:40:50.979 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Got packet: Control { timestamp: 1485695, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812784, 812812] }) }
Dec 02 15:40:50.979 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Loss list=[812784, 812812, 812812, 812784, 812812]
Dec 02 15:40:50.979 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812784)
Dec 02 15:40:50.987 INFO [stdout] 2018-12-02 15:40:50 INFO  [srt::receiver] lr=812783, buffer=[812825, 812824, 812823, 812822, 812821, 812820, 812819, 812818, 812817, 812816, 812815, 812814, 812813, 812811, 812810, 812809, 812808, 812807, 812806, 812805, 812804, 812803, 812802, 812801, 812800, 812799, 812798, 812797, 812796, 812795, 812794, 812793, 812792, 812791, 812790, 812789, 812788, 812787, 812786, 812785, 812784]
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812784)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812785)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812786)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812787)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812788)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812789)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812790)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812791)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812792)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812793)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812794)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812795)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812796)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812797)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812798)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812799)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812800)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812801)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812802)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812803)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812804)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812805)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812806)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812807)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812808)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812809)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812810)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812811)
Dec 02 15:40:51.073 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812812), lr_ack_acked=SeqNumber(812784)
Dec 02 15:40:51.195 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812812), lr_ack_acked=SeqNumber(812784)
Dec 02 15:40:51.196 INFO [stdout] 2018-12-02 15:40:51 INFO  [tests] Dropping packet: (Control { timestamp: 1728254, dest_sockid: SocketID(13), control_type: Ack(9, AckControlInfo { ack_number: SeqNumber(812812), rtt: Some(21327), rtt_variance: Some(25974), buffer_available: None, packet_recv_rate: Some(1141987), est_link_cap: Some(0) }) }, V4(0.0.0.0:0))
Dec 02 15:40:51.196 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812812] [SeqNumber(812812)]
Dec 02 15:40:51.196 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812812)]
Dec 02 15:40:51.206 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Got packet: Control { timestamp: 1607376, dest_sockid: SocketID(13), control_type: Ack(8, AckControlInfo { ack_number: SeqNumber(812812), rtt: Some(21327), rtt_variance: Some(25974), buffer_available: None, packet_recv_rate: Some(1141987), est_link_cap: Some(0) }) }
Dec 02 15:40:51.206 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:40:51.206 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Got packet: Control { timestamp: 1728338, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812812] }) }
Dec 02 15:40:51.206 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Loss list=[812812, 812812, 812812, 812812]
Dec 02 15:40:51.206 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812812)
Dec 02 15:40:51.206 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] lr=812811, buffer=[812825, 812824, 812823, 812822, 812821, 812820, 812819, 812818, 812817, 812816, 812815, 812814, 812813, 812812]
Dec 02 15:40:51.209 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812812)
Dec 02 15:40:51.210 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812812)
Dec 02 15:40:51.210 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812813)
Dec 02 15:40:51.210 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812814)
Dec 02 15:40:51.210 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812815)
Dec 02 15:40:51.210 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812816)
Dec 02 15:40:51.210 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812817)
Dec 02 15:40:51.210 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812818)
Dec 02 15:40:51.211 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812819)
Dec 02 15:40:51.211 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812820)
Dec 02 15:40:51.211 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812821)
Dec 02 15:40:51.211 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812822)
Dec 02 15:40:51.211 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812823)
Dec 02 15:40:51.211 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812824)
Dec 02 15:40:51.211 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812825)
Dec 02 15:40:51.211 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Received packet SeqNumber(812812) twice
Dec 02 15:40:51.221 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812812)
Dec 02 15:40:51.221 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Received packet SeqNumber(812812) twice
Dec 02 15:40:51.233 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812812)
Dec 02 15:40:51.237 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Received packet SeqNumber(812812) twice
Dec 02 15:40:51.251 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Sending packet: 812827
Dec 02 15:40:51.251 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812826)]
Dec 02 15:40:51.251 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] lr=812825, buffer=[812827]
Dec 02 15:40:51.251 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Got packet: Control { timestamp: 1782209, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812826] }) }
Dec 02 15:40:51.251 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Loss list=[812826]
Dec 02 15:40:51.259 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812826)
Dec 02 15:40:51.259 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] lr=812825, buffer=[812827, 812826]
Dec 02 15:40:51.276 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Sending packet: 812828
Dec 02 15:40:51.276 INFO [stdout] 2018-12-02 15:40:51 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812828), message_loc: Only, in_order_delivery: false, message_number: 97, timestamp: 1804151, dest_sockid: SocketID(81), payload: b"812828" }, V4(0.0.0.0:0))
Dec 02 15:40:51.285 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Sending packet: 812829
Dec 02 15:40:51.285 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812826)
Dec 02 15:40:51.285 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812827)
Dec 02 15:40:51.285 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812828)]
Dec 02 15:40:51.285 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] lr=812827, buffer=[812829]
Dec 02 15:40:51.285 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Got packet: Control { timestamp: 1815875, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812828] }) }
Dec 02 15:40:51.285 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Loss list=[812828]
Dec 02 15:40:51.299 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812828)
Dec 02 15:40:51.299 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] lr=812827, buffer=[812829, 812828]
Dec 02 15:40:51.399 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812828)
Dec 02 15:40:51.399 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Releasing SeqNumber(812829)
Dec 02 15:40:51.399 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812830), lr_ack_acked=SeqNumber(812812)
Dec 02 15:40:51.518 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Got packet: Control { timestamp: 1933986, dest_sockid: SocketID(13), control_type: Ack(10, AckControlInfo { ack_number: SeqNumber(812830), rtt: Some(34999), rtt_variance: Some(45664), buffer_available: None, packet_recv_rate: Some(1491862), est_link_cap: Some(0) }) }
Dec 02 15:40:51.519 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:40:51.519 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::sender] Sending packet: 812830
Dec 02 15:40:51.598 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812830), lr_ack_acked=SeqNumber(812812)
Dec 02 15:40:51.794 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812830), lr_ack_acked=SeqNumber(812812)
Dec 02 15:40:51.988 INFO [stdout] 2018-12-02 15:40:51 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812830), lr_ack_acked=SeqNumber(812812)
Dec 02 15:40:51.988 INFO [stdout] 2018-12-02 15:40:51 INFO  [tests] Dropping packet: (Control { timestamp: 2520846, dest_sockid: SocketID(13), control_type: Ack(13, AckControlInfo { ack_number: SeqNumber(812830), rtt: Some(34999), rtt_variance: Some(45664), buffer_available: None, packet_recv_rate: Some(1491862), est_link_cap: Some(0) }) }, V4(0.0.0.0:0))
Dec 02 15:40:52.073 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812829, buffer=[812830]
Dec 02 15:40:52.073 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Got packet: Control { timestamp: 2129580, dest_sockid: SocketID(13), control_type: Ack(11, AckControlInfo { ack_number: SeqNumber(812830), rtt: Some(34999), rtt_variance: Some(45664), buffer_available: None, packet_recv_rate: Some(1491862), est_link_cap: Some(0) }) }
Dec 02 15:40:52.073 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:40:52.073 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Got packet: Control { timestamp: 2325191, dest_sockid: SocketID(13), control_type: Ack(12, AckControlInfo { ack_number: SeqNumber(812830), rtt: Some(34999), rtt_variance: Some(45664), buffer_available: None, packet_recv_rate: Some(1491862), est_link_cap: Some(0) }) }
Dec 02 15:40:52.073 INFO [stdout] 2018-12-02 15:40:52 INFO  [tests] Dropping packet: (Control { timestamp: 2608316, dest_sockid: SocketID(81), control_type: Ack2(12) }, V4(0.0.0.0:0))
Dec 02 15:40:52.073 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:40:52.073 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812831
Dec 02 15:40:52.073 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Releasing SeqNumber(812830)
Dec 02 15:40:52.073 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812830, buffer=[812831]
Dec 02 15:40:52.105 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812832
Dec 02 15:40:52.105 INFO [stdout] 2018-12-02 15:40:52 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812833), message_loc: Only, in_order_delivery: false, message_number: 102, timestamp: 2639926, dest_sockid: SocketID(81), payload: b"812833" }, V4(0.0.0.0:0))
Dec 02 15:40:52.105 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Releasing SeqNumber(812831)
Dec 02 15:40:52.105 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812831, buffer=[812832]
Dec 02 15:40:52.125 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812834
Dec 02 15:40:52.127 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Releasing SeqNumber(812832)
Dec 02 15:40:52.127 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812833)]
Dec 02 15:40:52.127 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812832, buffer=[812834]
Dec 02 15:40:52.127 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Got packet: Control { timestamp: 2662352, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812833] }) }
Dec 02 15:40:52.127 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Loss list=[812833]
Dec 02 15:40:52.138 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812833)
Dec 02 15:40:52.139 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812832, buffer=[812834, 812833]
Dec 02 15:40:52.154 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812835
Dec 02 15:40:52.154 INFO [stdout] 2018-12-02 15:40:52 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812835), message_loc: Only, in_order_delivery: false, message_number: 104, timestamp: 2689314, dest_sockid: SocketID(81), payload: b"812835" }, V4(0.0.0.0:0))
Dec 02 15:40:52.168 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812836
Dec 02 15:40:52.171 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Releasing SeqNumber(812833)
Dec 02 15:40:52.171 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Releasing SeqNumber(812834)
Dec 02 15:40:52.171 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812835)]
Dec 02 15:40:52.171 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812834, buffer=[812836]
Dec 02 15:40:52.171 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Got packet: Control { timestamp: 2706056, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812835] }) }
Dec 02 15:40:52.171 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Loss list=[812835]
Dec 02 15:40:52.182 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812835)
Dec 02 15:40:52.182 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812834, buffer=[812836, 812835]
Dec 02 15:40:52.199 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812837
Dec 02 15:40:52.203 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Releasing SeqNumber(812835)
Dec 02 15:40:52.203 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Releasing SeqNumber(812836)
Dec 02 15:40:52.203 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812836, buffer=[812837]
Dec 02 15:40:52.215 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812838
Dec 02 15:40:52.215 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Releasing SeqNumber(812837)
Dec 02 15:40:52.215 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812837, buffer=[812838]
Dec 02 15:40:52.231 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812839
Dec 02 15:40:52.231 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Releasing SeqNumber(812838)
Dec 02 15:40:52.231 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812838, buffer=[812839]
Dec 02 15:40:52.247 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812840
Dec 02 15:40:52.247 INFO [stdout] 2018-12-02 15:40:52 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812840), message_loc: Only, in_order_delivery: false, message_number: 109, timestamp: 2782412, dest_sockid: SocketID(81), payload: b"812840" }, V4(0.0.0.0:0))
Dec 02 15:40:52.264 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812841
Dec 02 15:40:52.264 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Releasing SeqNumber(812839)
Dec 02 15:40:52.264 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812840)]
Dec 02 15:40:52.264 INFO [stdout] 2018-12-02 15:40:52 INFO  [tests] Dropping packet: (Control { timestamp: 2798397, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812840] }) }, V4(0.0.0.0:0))
Dec 02 15:40:52.264 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812841]
Dec 02 15:40:52.279 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812842
Dec 02 15:40:52.279 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812842, 812841]
Dec 02 15:40:52.298 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812843
Dec 02 15:40:52.298 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812843, 812842, 812841]
Dec 02 15:40:52.315 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812844
Dec 02 15:40:52.316 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812844, 812843, 812842, 812841]
Dec 02 15:40:52.362 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812845
Dec 02 15:40:52.362 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:52.382 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812846
Dec 02 15:40:52.382 INFO [stdout] 2018-12-02 15:40:52 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812846), message_loc: Only, in_order_delivery: false, message_number: 115, timestamp: 2915631, dest_sockid: SocketID(81), payload: b"812846" }, V4(0.0.0.0:0))
Dec 02 15:40:52.398 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812847
Dec 02 15:40:52.399 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812846)]
Dec 02 15:40:52.399 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:52.399 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Got packet: Control { timestamp: 2933622, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812846] }) }
Dec 02 15:40:52.399 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Loss list=[812846]
Dec 02 15:40:52.427 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812846)
Dec 02 15:40:52.427 INFO [stdout] 2018-12-02 15:40:52 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812846), message_loc: Only, in_order_delivery: false, message_number: 115, timestamp: 2915631, dest_sockid: SocketID(81), payload: b"812846" }, V4(0.0.0.0:0))
Dec 02 15:40:52.455 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812848
Dec 02 15:40:52.455 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:52.455 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:52.479 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812850
Dec 02 15:40:52.479 INFO [stdout] 2018-12-02 15:40:52 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812850), message_loc: Only, in_order_delivery: false, message_number: 119, timestamp: 3012229, dest_sockid: SocketID(81), payload: b"812850" }, V4(0.0.0.0:0))
Dec 02 15:40:52.503 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812851
Dec 02 15:40:52.507 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812850)]
Dec 02 15:40:52.507 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812851, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:52.507 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Got packet: Control { timestamp: 3039593, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812850] }) }
Dec 02 15:40:52.507 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Loss list=[812850]
Dec 02 15:40:52.519 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812850)
Dec 02 15:40:52.523 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:52.539 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812852
Dec 02 15:40:52.539 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:52.567 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812853
Dec 02 15:40:52.567 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:52.824 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812854
Dec 02 15:40:52.824 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:52.850 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812855
Dec 02 15:40:52.850 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:52.870 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812856
Dec 02 15:40:52.870 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:52.891 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::sender] Sending packet: 812857
Dec 02 15:40:52.895 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] lr=812839, buffer=[812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:52.944 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812840), lr_ack_acked=SeqNumber(812830)
Dec 02 15:40:52.944 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812840, 812846] [SeqNumber(812840), SeqNumber(812846)]
Dec 02 15:40:52.944 INFO [stdout] 2018-12-02 15:40:52 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812840), SeqNumber(812846)]
Dec 02 15:40:52.944 INFO [stdout] 2018-12-02 15:40:52 INFO  [tests] Dropping packet: (Control { timestamp: 3475505, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812840, 812846] }) }, V4(0.0.0.0:0))
Dec 02 15:40:53.654 INFO [stdout] 2018-12-02 15:40:53 INFO  [srt::sender] Got packet: Control { timestamp: 3475441, dest_sockid: SocketID(13), control_type: Ack(14, AckControlInfo { ack_number: SeqNumber(812840), rtt: Some(160370), rtt_variance: Some(215380), buffer_available: None, packet_recv_rate: Some(3059335), est_link_cap: Some(0) }) }
Dec 02 15:40:53.654 INFO [stdout] 2018-12-02 15:40:53 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:40:53.654 INFO [stdout] 2018-12-02 15:40:53 INFO  [srt::sender] Sending packet: 812858
Dec 02 15:40:53.659 INFO [stdout] 2018-12-02 15:40:53 INFO  [srt::receiver] lr=812839, buffer=[812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:53.795 INFO [stdout] 2018-12-02 15:40:53 INFO  [srt::sender] Sending packet: 812859
Dec 02 15:40:53.795 INFO [stdout] 2018-12-02 15:40:53 INFO  [srt::receiver] lr=812839, buffer=[812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:53.971 INFO [stdout] 2018-12-02 15:40:53 INFO  [srt::sender] Sending packet: 812860
Dec 02 15:40:53.975 INFO [stdout] 2018-12-02 15:40:53 INFO  [srt::receiver] lr=812839, buffer=[812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:53.999 INFO [stdout] 2018-12-02 15:40:53 INFO  [srt::sender] Sending packet: 812861
Dec 02 15:40:53.999 INFO [stdout] 2018-12-02 15:40:53 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812861), message_loc: Only, in_order_delivery: false, message_number: 130, timestamp: 4530467, dest_sockid: SocketID(81), payload: b"812861" }, V4(0.0.0.0:0))
Dec 02 15:40:54.379 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Sending packet: 812862
Dec 02 15:40:54.379 INFO [stdout] 2018-12-02 15:40:54 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812862), message_loc: Only, in_order_delivery: false, message_number: 131, timestamp: 4910165, dest_sockid: SocketID(81), payload: b"812862" }, V4(0.0.0.0:0))
Dec 02 15:40:54.403 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Sending packet: 812863
Dec 02 15:40:54.403 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812861), SeqNumber(812862)]
Dec 02 15:40:54.403 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::receiver] lr=812839, buffer=[812863, 812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:54.403 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Got packet: Control { timestamp: 4934057, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670787, 812862] }) }
Dec 02 15:40:54.403 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Loss list=[812861, 812862]
Dec 02 15:40:54.423 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812861)
Dec 02 15:40:54.423 INFO [stdout] 2018-12-02 15:40:54 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812861), message_loc: Only, in_order_delivery: false, message_number: 130, timestamp: 4530467, dest_sockid: SocketID(81), payload: b"812861" }, V4(0.0.0.0:0))
Dec 02 15:40:54.447 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812862)
Dec 02 15:40:54.450 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::receiver] lr=812839, buffer=[812863, 812862, 812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:54.467 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Sending packet: 812864
Dec 02 15:40:54.467 INFO [stdout] 2018-12-02 15:40:54 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812865), message_loc: Only, in_order_delivery: false, message_number: 134, timestamp: 5002315, dest_sockid: SocketID(81), payload: b"812865" }, V4(0.0.0.0:0))
Dec 02 15:40:54.467 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::receiver] lr=812839, buffer=[812864, 812863, 812862, 812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:54.495 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Sending packet: 812866
Dec 02 15:40:54.495 INFO [stdout] 2018-12-02 15:40:54 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812866), message_loc: Only, in_order_delivery: false, message_number: 135, timestamp: 5024424, dest_sockid: SocketID(81), payload: b"812866" }, V4(0.0.0.0:0))
Dec 02 15:40:54.537 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Sending packet: 812867
Dec 02 15:40:54.539 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812865), SeqNumber(812866)]
Dec 02 15:40:54.539 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::receiver] lr=812839, buffer=[812867, 812864, 812863, 812862, 812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:54.539 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Got packet: Control { timestamp: 5072217, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670783, 812866] }) }
Dec 02 15:40:54.539 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Loss list=[812865, 812866]
Dec 02 15:40:54.806 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812865)
Dec 02 15:40:54.818 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::receiver] lr=812839, buffer=[812867, 812865, 812864, 812863, 812862, 812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:54.858 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812866)
Dec 02 15:40:54.859 INFO [stdout] 2018-12-02 15:40:54 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812866), message_loc: Only, in_order_delivery: false, message_number: 135, timestamp: 5024424, dest_sockid: SocketID(81), payload: b"812866" }, V4(0.0.0.0:0))
Dec 02 15:40:54.879 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812840, 812846, 812861, 812866] [SeqNumber(812840), SeqNumber(812846), SeqNumber(812861)]
Dec 02 15:40:54.879 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812840), SeqNumber(812846), SeqNumber(812861)]
Dec 02 15:40:54.931 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Got packet: Control { timestamp: 5412421, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812840, 812846, 812861] }) }
Dec 02 15:40:54.931 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Loss list=[812840, 812846, 812861]
Dec 02 15:40:54.931 INFO [stdout] 2018-12-02 15:40:54 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812840)
Dec 02 15:40:54.931 INFO [stdout] 2018-12-02 15:40:54 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812840), message_loc: Only, in_order_delivery: false, message_number: 109, timestamp: 2782412, dest_sockid: SocketID(81), payload: b"812840" }, V4(0.0.0.0:0))
Dec 02 15:40:55.477 INFO [stdout] 2018-12-02 15:40:55 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812846)
Dec 02 15:40:55.477 INFO [stdout] 2018-12-02 15:40:55 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812846), message_loc: Only, in_order_delivery: false, message_number: 115, timestamp: 2915631, dest_sockid: SocketID(81), payload: b"812846" }, V4(0.0.0.0:0))
Dec 02 15:40:56.099 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812840, 812846, 812861, 812866] [SeqNumber(812840), SeqNumber(812846), SeqNumber(812861), SeqNumber(812866)]
Dec 02 15:40:56.103 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812840), SeqNumber(812846), SeqNumber(812861), SeqNumber(812866)]
Dec 02 15:40:56.316 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::sender] Got packet: Control { timestamp: 6630789, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812840, 812846, 812861, 812866] }) }
Dec 02 15:40:56.316 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::sender] Loss list=[812861, 812840, 812846, 812861, 812866]
Dec 02 15:40:56.316 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812861)
Dec 02 15:40:56.316 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] lr=812839, buffer=[812867, 812865, 812864, 812863, 812862, 812861, 812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841]
Dec 02 15:40:56.346 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812840)
Dec 02 15:40:56.347 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] lr=812839, buffer=[812867, 812865, 812864, 812863, 812862, 812861, 812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812845, 812844, 812843, 812842, 812841, 812840]
Dec 02 15:40:56.471 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812846)
Dec 02 15:40:56.471 INFO [stdout] 2018-12-02 15:40:56 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812846), message_loc: Only, in_order_delivery: false, message_number: 115, timestamp: 2915631, dest_sockid: SocketID(81), payload: b"812846" }, V4(0.0.0.0:0))
Dec 02 15:40:56.499 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812861)
Dec 02 15:40:56.500 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] Releasing SeqNumber(812840)
Dec 02 15:40:56.500 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] Releasing SeqNumber(812841)
Dec 02 15:40:56.500 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] Releasing SeqNumber(812842)
Dec 02 15:40:56.500 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] Releasing SeqNumber(812843)
Dec 02 15:40:56.501 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] Releasing SeqNumber(812844)
Dec 02 15:40:56.501 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] Releasing SeqNumber(812845)
Dec 02 15:40:56.501 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] lr=812845, buffer=[812867, 812865, 812864, 812863, 812862, 812861, 812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847]
Dec 02 15:40:56.555 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812866)
Dec 02 15:40:56.755 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::sender] Sending packet: 812868
Dec 02 15:40:56.755 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] lr=812845, buffer=[812867, 812866, 812865, 812864, 812863, 812862, 812861, 812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847]
Dec 02 15:40:56.755 INFO [stdout] 2018-12-02 15:40:56 INFO  [srt::receiver] lr=812845, buffer=[812868, 812867, 812866, 812865, 812864, 812863, 812862, 812861, 812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847]
Dec 02 15:40:57.008 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::sender] Sending packet: 812869
Dec 02 15:40:57.008 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] lr=812845, buffer=[812869, 812868, 812867, 812866, 812865, 812864, 812863, 812862, 812861, 812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847]
Dec 02 15:40:57.315 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812846), lr_ack_acked=SeqNumber(812840)
Dec 02 15:40:57.315 INFO [stdout] 2018-12-02 15:40:57 INFO  [tests] Dropping packet: (Control { timestamp: 7848531, dest_sockid: SocketID(13), control_type: Ack(15, AckControlInfo { ack_number: SeqNumber(812846), rtt: Some(230170), rtt_variance: Some(287383), buffer_available: None, packet_recv_rate: Some(5504534), est_link_cap: Some(0) }) }, V4(0.0.0.0:0))
Dec 02 15:40:57.315 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812846] [SeqNumber(812846)]
Dec 02 15:40:57.315 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812846)]
Dec 02 15:40:57.803 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::sender] Got packet: Control { timestamp: 7848616, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812846] }) }
Dec 02 15:40:57.803 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::sender] Loss list=[812846]
Dec 02 15:40:57.803 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812846)
Dec 02 15:40:57.803 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] lr=812845, buffer=[812869, 812868, 812867, 812866, 812865, 812864, 812863, 812862, 812861, 812860, 812859, 812858, 812857, 812856, 812855, 812854, 812853, 812852, 812851, 812850, 812849, 812848, 812847, 812846]
Dec 02 15:40:57.838 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::sender] Sending packet: 812870
Dec 02 15:40:57.839 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812846)
Dec 02 15:40:57.839 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812847)
Dec 02 15:40:57.839 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812848)
Dec 02 15:40:57.839 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812849)
Dec 02 15:40:57.839 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812850)
Dec 02 15:40:57.839 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812851)
Dec 02 15:40:57.839 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812852)
Dec 02 15:40:57.839 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812853)
Dec 02 15:40:57.839 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812854)
Dec 02 15:40:57.839 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812855)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812856)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812857)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812858)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812859)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812860)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812861)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812862)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812863)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812864)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812865)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812866)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812867)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812868)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] Releasing SeqNumber(812869)
Dec 02 15:40:57.840 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::receiver] lr=812869, buffer=[812870]
Dec 02 15:40:57.942 INFO [stdout] 2018-12-02 15:40:57 INFO  [srt::sender] Sending packet: 812871
Dec 02 15:40:58.535 INFO [stdout] 2018-12-02 15:40:58 INFO  [srt::receiver] Releasing SeqNumber(812870)
Dec 02 15:40:58.535 INFO [stdout] 2018-12-02 15:40:58 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812871), lr_ack_acked=SeqNumber(812840)
Dec 02 15:40:59.331 INFO [stdout] 2018-12-02 15:40:59 INFO  [srt::receiver] lr=812870, buffer=[812871]
Dec 02 15:40:59.331 INFO [stdout] 2018-12-02 15:40:59 INFO  [srt::sender] Got packet: Control { timestamp: 9066765, dest_sockid: SocketID(13), control_type: Ack(16, AckControlInfo { ack_number: SeqNumber(812871), rtt: Some(230170), rtt_variance: Some(287383), buffer_available: None, packet_recv_rate: Some(6122028), est_link_cap: Some(0) }) }
Dec 02 15:40:59.331 INFO [stdout] 2018-12-02 15:40:59 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:40:59.331 INFO [stdout] 2018-12-02 15:40:59 INFO  [srt::sender] Sending packet: 812872
Dec 02 15:40:59.336 INFO [stdout] 2018-12-02 15:40:59 INFO  [srt::receiver] Releasing SeqNumber(812871)
Dec 02 15:40:59.336 INFO [stdout] 2018-12-02 15:40:59 INFO  [srt::receiver] lr=812871, buffer=[812872]
Dec 02 15:40:59.371 INFO [stdout] 2018-12-02 15:40:59 INFO  [srt::sender] Sending packet: 812873
Dec 02 15:40:59.376 INFO [stdout] 2018-12-02 15:40:59 INFO  [srt::receiver] Releasing SeqNumber(812872)
Dec 02 15:40:59.376 INFO [stdout] 2018-12-02 15:40:59 INFO  [srt::receiver] lr=812872, buffer=[812873]
Dec 02 15:40:59.411 INFO [stdout] 2018-12-02 15:40:59 INFO  [srt::sender] Sending packet: 812874
Dec 02 15:40:59.412 INFO [stdout] 2018-12-02 15:40:59 INFO  [srt::receiver] Releasing SeqNumber(812873)
Dec 02 15:40:59.412 INFO [stdout] 2018-12-02 15:40:59 INFO  [srt::receiver] lr=812873, buffer=[812874]
Dec 02 15:41:00.171 INFO [stdout] 2018-12-02 15:41:00 INFO  [srt::sender] Sending packet: 812875
Dec 02 15:41:00.412 INFO [stdout] 2018-12-02 15:41:00 INFO  [srt::receiver] Releasing SeqNumber(812874)
Dec 02 15:41:00.774 INFO [stdout] 2018-12-02 15:41:00 INFO  [srt::receiver] lr=812874, buffer=[812875]
Dec 02 15:41:00.774 INFO [stdout] 2018-12-02 15:41:00 INFO  [srt::sender] Sending packet: 812876
Dec 02 15:41:00.774 INFO [stdout] 2018-12-02 15:41:00 INFO  [srt::receiver] Releasing SeqNumber(812875)
Dec 02 15:41:00.774 INFO [stdout] 2018-12-02 15:41:00 INFO  [srt::receiver] lr=812875, buffer=[812876]
Dec 02 15:41:00.807 INFO [stdout] 2018-12-02 15:41:00 INFO  [srt::sender] Sending packet: 812877
Dec 02 15:41:00.807 INFO [stdout] 2018-12-02 15:41:00 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812877), message_loc: Only, in_order_delivery: false, message_number: 146, timestamp: 11341112, dest_sockid: SocketID(81), payload: b"812877" }, V4(0.0.0.0:0))
Dec 02 15:41:00.847 INFO [stdout] 2018-12-02 15:41:00 INFO  [srt::sender] Sending packet: 812878
Dec 02 15:41:00.903 INFO [stdout] 2018-12-02 15:41:00 INFO  [srt::receiver] Releasing SeqNumber(812876)
Dec 02 15:41:00.903 INFO [stdout] 2018-12-02 15:41:00 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812877), lr_ack_acked=SeqNumber(812871)
Dec 02 15:41:00.903 INFO [stdout] 2018-12-02 15:41:00 INFO  [tests] Dropping packet: (Control { timestamp: 11433979, dest_sockid: SocketID(13), control_type: Ack(17, AckControlInfo { ack_number: SeqNumber(812877), rtt: Some(301953), rtt_variance: Some(344800), buffer_available: None, packet_recv_rate: Some(8263583), est_link_cap: Some(0) }) }, V4(0.0.0.0:0))
Dec 02 15:41:01.485 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812877)]
Dec 02 15:41:01.485 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::receiver] lr=812876, buffer=[812878]
Dec 02 15:41:01.485 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::sender] Sending packet: 812879
Dec 02 15:41:01.485 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::sender] Got packet: Control { timestamp: 12018255, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812877] }) }
Dec 02 15:41:01.485 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::sender] Loss list=[812877]
Dec 02 15:41:01.485 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::receiver] lr=812876, buffer=[812879, 812878]
Dec 02 15:41:01.522 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812877)
Dec 02 15:41:01.522 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::receiver] lr=812876, buffer=[812879, 812878, 812877]
Dec 02 15:41:01.563 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::sender] Sending packet: 812880
Dec 02 15:41:01.563 INFO [stdout] 2018-12-02 15:41:01 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812880), message_loc: Only, in_order_delivery: false, message_number: 149, timestamp: 12096153, dest_sockid: SocketID(81), payload: b"812880" }, V4(0.0.0.0:0))
Dec 02 15:41:01.563 INFO [stdout] 2018-12-02 15:41:01 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812881), message_loc: Only, in_order_delivery: false, message_number: 150, timestamp: 12096196, dest_sockid: SocketID(81), payload: b"812881" }, V4(0.0.0.0:0))
Dec 02 15:41:01.699 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::sender] Sending packet: 812882
Dec 02 15:41:01.699 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::receiver] Releasing SeqNumber(812877)
Dec 02 15:41:01.699 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::receiver] Releasing SeqNumber(812878)
Dec 02 15:41:01.699 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::receiver] Releasing SeqNumber(812879)
Dec 02 15:41:01.699 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812880), SeqNumber(812881)]
Dec 02 15:41:01.699 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::receiver] lr=812879, buffer=[812882]
Dec 02 15:41:01.699 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::sender] Got packet: Control { timestamp: 12230321, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670768, 812881] }) }
Dec 02 15:41:01.699 INFO [stdout] 2018-12-02 15:41:01 INFO  [srt::sender] Loss list=[812880, 812881]
Dec 02 15:41:02.151 INFO [stdout] 2018-12-02 15:41:02 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812880)
Dec 02 15:41:02.151 INFO [stdout] 2018-12-02 15:41:02 INFO  [srt::receiver] lr=812879, buffer=[812882, 812880]
Dec 02 15:41:02.403 INFO [stdout] 2018-12-02 15:41:02 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812881)
Dec 02 15:41:02.404 INFO [stdout] 2018-12-02 15:41:02 INFO  [srt::receiver] Releasing SeqNumber(812880)
Dec 02 15:41:02.404 INFO [stdout] 2018-12-02 15:41:02 INFO  [srt::receiver] lr=812880, buffer=[812882, 812881]
Dec 02 15:41:02.471 INFO [stdout] 2018-12-02 15:41:02 INFO  [srt::receiver] Releasing SeqNumber(812881)
Dec 02 15:41:02.471 INFO [stdout] 2018-12-02 15:41:02 INFO  [srt::receiver] Releasing SeqNumber(812882)
Dec 02 15:41:02.471 INFO [stdout] 2018-12-02 15:41:02 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812883), lr_ack_acked=SeqNumber(812871)
Dec 02 15:41:02.711 INFO [stdout] 2018-12-02 15:41:02 INFO  [srt::sender] Got packet: Control { timestamp: 13002101, dest_sockid: SocketID(13), control_type: Ack(18, AckControlInfo { ack_number: SeqNumber(812883), rtt: Some(301953), rtt_variance: Some(344800), buffer_available: None, packet_recv_rate: Some(10481079), est_link_cap: Some(0) }) }
Dec 02 15:41:02.711 INFO [stdout] 2018-12-02 15:41:02 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:41:02.711 INFO [stdout] 2018-12-02 15:41:02 INFO  [srt::sender] Sending packet: 812883
Dec 02 15:41:02.711 INFO [stdout] 2018-12-02 15:41:02 INFO  [srt::receiver] lr=812882, buffer=[812883]
Dec 02 15:41:03.507 INFO [stdout] 2018-12-02 15:41:03 INFO  [srt::sender] Sending packet: 812884
Dec 02 15:41:03.507 INFO [stdout] 2018-12-02 15:41:03 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812884), message_loc: Only, in_order_delivery: false, message_number: 153, timestamp: 14042161, dest_sockid: SocketID(81), payload: b"812884" }, V4(0.0.0.0:0))
Dec 02 15:41:03.551 INFO [stdout] 2018-12-02 15:41:03 INFO  [srt::sender] Sending packet: 812885
Dec 02 15:41:03.551 INFO [stdout] 2018-12-02 15:41:03 INFO  [srt::receiver] Releasing SeqNumber(812883)
Dec 02 15:41:03.552 INFO [stdout] 2018-12-02 15:41:03 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812884)]
Dec 02 15:41:03.552 INFO [stdout] 2018-12-02 15:41:03 INFO  [srt::receiver] lr=812883, buffer=[812885]
Dec 02 15:41:03.552 INFO [stdout] 2018-12-02 15:41:03 INFO  [srt::sender] Got packet: Control { timestamp: 14087216, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812884] }) }
Dec 02 15:41:03.552 INFO [stdout] 2018-12-02 15:41:03 INFO  [srt::sender] Loss list=[812884]
Dec 02 15:41:03.593 INFO [stdout] 2018-12-02 15:41:03 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812884)
Dec 02 15:41:03.594 INFO [stdout] 2018-12-02 15:41:03 INFO  [srt::receiver] lr=812883, buffer=[812885, 812884]
Dec 02 15:41:04.179 INFO [stdout] 2018-12-02 15:41:04 INFO  [srt::receiver] Releasing SeqNumber(812884)
Dec 02 15:41:04.183 INFO [stdout] 2018-12-02 15:41:04 INFO  [srt::receiver] Releasing SeqNumber(812885)
Dec 02 15:41:04.183 INFO [stdout] 2018-12-02 15:41:04 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812886), lr_ack_acked=SeqNumber(812883)
Dec 02 15:41:04.775 INFO [stdout] 2018-12-02 15:41:04 INFO  [srt::sender] Got packet: Control { timestamp: 14718129, dest_sockid: SocketID(13), control_type: Ack(19, AckControlInfo { ack_number: SeqNumber(812886), rtt: Some(294274), rtt_variance: Some(284668), buffer_available: None, packet_recv_rate: Some(11624106), est_link_cap: Some(0) }) }
Dec 02 15:41:04.775 INFO [stdout] 2018-12-02 15:41:04 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:41:04.775 INFO [stdout] 2018-12-02 15:41:04 INFO  [srt::sender] Sending packet: 812886
Dec 02 15:41:04.903 INFO [stdout] 2018-12-02 15:41:04 INFO  [srt::receiver] lr=812885, buffer=[812886]
Dec 02 15:41:04.903 INFO [stdout] 2018-12-02 15:41:04 INFO  [srt::sender] Sending packet: 812887
Dec 02 15:41:04.903 INFO [stdout] 2018-12-02 15:41:04 INFO  [srt::receiver] Releasing SeqNumber(812886)
Dec 02 15:41:04.904 INFO [stdout] 2018-12-02 15:41:04 INFO  [srt::receiver] lr=812886, buffer=[812887]
Dec 02 15:41:05.119 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812888
Dec 02 15:41:05.119 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] Releasing SeqNumber(812887)
Dec 02 15:41:05.121 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812887, buffer=[812888]
Dec 02 15:41:05.167 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812889
Dec 02 15:41:05.167 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] Releasing SeqNumber(812888)
Dec 02 15:41:05.167 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812888, buffer=[812889]
Dec 02 15:41:05.287 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812890
Dec 02 15:41:05.429 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812891
Dec 02 15:41:05.429 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] Releasing SeqNumber(812889)
Dec 02 15:41:05.429 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812889, buffer=[812890]
Dec 02 15:41:05.430 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] Releasing SeqNumber(812890)
Dec 02 15:41:05.430 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812890, buffer=[812891]
Dec 02 15:41:05.475 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812892
Dec 02 15:41:05.483 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] Releasing SeqNumber(812891)
Dec 02 15:41:05.483 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812891, buffer=[812892]
Dec 02 15:41:05.535 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812893
Dec 02 15:41:05.535 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] Releasing SeqNumber(812892)
Dec 02 15:41:05.535 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812892, buffer=[812893]
Dec 02 15:41:05.564 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812894
Dec 02 15:41:05.564 INFO [stdout] 2018-12-02 15:41:05 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812894), message_loc: Only, in_order_delivery: false, message_number: 163, timestamp: 16099430, dest_sockid: SocketID(81), payload: b"812894" }, V4(0.0.0.0:0))
Dec 02 15:41:05.609 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812895
Dec 02 15:41:05.614 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] Releasing SeqNumber(812893)
Dec 02 15:41:05.615 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812894)]
Dec 02 15:41:05.615 INFO [stdout] 2018-12-02 15:41:05 INFO  [tests] Dropping packet: (Control { timestamp: 16146299, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812894] }) }, V4(0.0.0.0:0))
Dec 02 15:41:05.615 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812893, buffer=[812895]
Dec 02 15:41:05.670 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812896
Dec 02 15:41:05.671 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812893, buffer=[812896, 812895]
Dec 02 15:41:05.671 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812893, buffer=[812897, 812896, 812895]
Dec 02 15:41:05.713 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812898
Dec 02 15:41:05.713 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812893, buffer=[812898, 812897, 812896, 812895]
Dec 02 15:41:05.891 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812899
Dec 02 15:41:05.891 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812893, buffer=[812899, 812898, 812897, 812896, 812895]
Dec 02 15:41:05.935 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812900
Dec 02 15:41:05.935 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812893, buffer=[812900, 812899, 812898, 812897, 812896, 812895]
Dec 02 15:41:05.982 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::sender] Sending packet: 812901
Dec 02 15:41:05.982 INFO [stdout] 2018-12-02 15:41:05 INFO  [srt::receiver] lr=812893, buffer=[812901, 812900, 812899, 812898, 812897, 812896, 812895]
Dec 02 15:41:06.026 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet: 812902
Dec 02 15:41:06.026 INFO [stdout] 2018-12-02 15:41:06 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812902), message_loc: Only, in_order_delivery: false, message_number: 171, timestamp: 16561035, dest_sockid: SocketID(81), payload: b"812902" }, V4(0.0.0.0:0))
Dec 02 15:41:06.331 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet: 812903
Dec 02 15:41:06.331 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812902)]
Dec 02 15:41:06.331 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] lr=812893, buffer=[812903, 812901, 812900, 812899, 812898, 812897, 812896, 812895]
Dec 02 15:41:06.331 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Got packet: Control { timestamp: 16863618, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812902] }) }
Dec 02 15:41:06.331 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Loss list=[812902]
Dec 02 15:41:06.373 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812902)
Dec 02 15:41:06.373 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] lr=812893, buffer=[812903, 812902, 812901, 812900, 812899, 812898, 812897, 812896, 812895]
Dec 02 15:41:06.388 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812894), lr_ack_acked=SeqNumber(812886)
Dec 02 15:41:06.388 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812894] [SeqNumber(812894)]
Dec 02 15:41:06.388 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812894)]
Dec 02 15:41:06.388 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Got packet: Control { timestamp: 16922663, dest_sockid: SocketID(13), control_type: Ack(20, AckControlInfo { ack_number: SeqNumber(812894), rtt: Some(330497), rtt_variance: Some(288350), buffer_available: None, packet_recv_rate: Some(16172622), est_link_cap: Some(0) }) }
Dec 02 15:41:06.388 INFO [stdout] 2018-12-02 15:41:06 INFO  [tests] Dropping packet: (Control { timestamp: 16922934, dest_sockid: SocketID(81), control_type: Ack2(20) }, V4(0.0.0.0:0))
Dec 02 15:41:06.388 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:41:06.388 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Got packet: Control { timestamp: 16922728, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812894] }) }
Dec 02 15:41:06.388 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Loss list=[812894]
Dec 02 15:41:06.429 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812894)
Dec 02 15:41:06.429 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] lr=812893, buffer=[812903, 812902, 812901, 812900, 812899, 812898, 812897, 812896, 812895, 812894]
Dec 02 15:41:06.478 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet: 812904
Dec 02 15:41:06.478 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Releasing SeqNumber(812894)
Dec 02 15:41:06.478 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Releasing SeqNumber(812895)
Dec 02 15:41:06.478 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Releasing SeqNumber(812896)
Dec 02 15:41:06.478 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Releasing SeqNumber(812897)
Dec 02 15:41:06.478 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Releasing SeqNumber(812898)
Dec 02 15:41:06.478 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Releasing SeqNumber(812899)
Dec 02 15:41:06.478 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Releasing SeqNumber(812900)
Dec 02 15:41:06.478 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Releasing SeqNumber(812901)
Dec 02 15:41:06.478 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Releasing SeqNumber(812902)
Dec 02 15:41:06.478 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Releasing SeqNumber(812903)
Dec 02 15:41:06.478 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] lr=812903, buffer=[812904]
Dec 02 15:41:06.532 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet: 812905
Dec 02 15:41:06.533 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Releasing SeqNumber(812904)
Dec 02 15:41:06.533 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] lr=812904, buffer=[812905]
Dec 02 15:41:06.586 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet: 812906
Dec 02 15:41:06.586 INFO [stdout] 2018-12-02 15:41:06 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812906), message_loc: Only, in_order_delivery: false, message_number: 175, timestamp: 17121405, dest_sockid: SocketID(81), payload: b"812906" }, V4(0.0.0.0:0))
Dec 02 15:41:06.640 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet: 812907
Dec 02 15:41:06.640 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Releasing SeqNumber(812905)
Dec 02 15:41:06.640 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812906)]
Dec 02 15:41:06.640 INFO [stdout] 2018-12-02 15:41:06 INFO  [tests] Dropping packet: (Control { timestamp: 17175246, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812906] }) }, V4(0.0.0.0:0))
Dec 02 15:41:06.640 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] lr=812905, buffer=[812907]
Dec 02 15:41:06.699 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet: 812908
Dec 02 15:41:06.699 INFO [stdout] 2018-12-02 15:41:06 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812908), message_loc: Only, in_order_delivery: false, message_number: 177, timestamp: 17228952, dest_sockid: SocketID(81), payload: b"812908" }, V4(0.0.0.0:0))
Dec 02 15:41:06.751 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet: 812909
Dec 02 15:41:06.751 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812908)]
Dec 02 15:41:06.751 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] lr=812905, buffer=[812909, 812907]
Dec 02 15:41:06.751 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Got packet: Control { timestamp: 17282976, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812908] }) }
Dec 02 15:41:06.751 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Loss list=[812908]
Dec 02 15:41:06.803 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812908)
Dec 02 15:41:06.803 INFO [stdout] 2018-12-02 15:41:06 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812908), message_loc: Only, in_order_delivery: false, message_number: 177, timestamp: 17228952, dest_sockid: SocketID(81), payload: b"812908" }, V4(0.0.0.0:0))
Dec 02 15:41:06.863 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet: 812910
Dec 02 15:41:06.863 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] lr=812905, buffer=[812910, 812909, 812907]
Dec 02 15:41:06.922 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet: 812911
Dec 02 15:41:06.923 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] lr=812905, buffer=[812911, 812910, 812909, 812907]
Dec 02 15:41:06.998 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::sender] Sending packet: 812912
Dec 02 15:41:06.998 INFO [stdout] 2018-12-02 15:41:06 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812913), message_loc: Only, in_order_delivery: false, message_number: 182, timestamp: 17517929, dest_sockid: SocketID(81), payload: b"812913" }, V4(0.0.0.0:0))
Dec 02 15:41:06.998 INFO [stdout] 2018-12-02 15:41:06 INFO  [srt::receiver] lr=812905, buffer=[812912, 812911, 812910, 812909, 812907]
Dec 02 15:41:07.043 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet: 812914
Dec 02 15:41:07.043 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812913)]
Dec 02 15:41:07.043 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] lr=812905, buffer=[812914, 812912, 812911, 812910, 812909, 812907]
Dec 02 15:41:07.043 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Got packet: Control { timestamp: 17578579, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812913] }) }
Dec 02 15:41:07.043 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Loss list=[812913]
Dec 02 15:41:07.104 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812913)
Dec 02 15:41:07.104 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] lr=812905, buffer=[812914, 812913, 812912, 812911, 812910, 812909, 812907]
Dec 02 15:41:07.175 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet: 812915
Dec 02 15:41:07.175 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] lr=812905, buffer=[812915, 812914, 812913, 812912, 812911, 812910, 812909, 812907]
Dec 02 15:41:07.240 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet: 812916
Dec 02 15:41:07.240 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] lr=812905, buffer=[812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812907]
Dec 02 15:41:07.308 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet: 812917
Dec 02 15:41:07.308 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] lr=812905, buffer=[812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812907]
Dec 02 15:41:07.376 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet: 812918
Dec 02 15:41:07.383 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] lr=812905, buffer=[812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812907]
Dec 02 15:41:07.446 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet: 812919
Dec 02 15:41:07.451 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] lr=812905, buffer=[812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812907]
Dec 02 15:41:07.554 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet: 812920
Dec 02 15:41:07.555 INFO [stdout] 2018-12-02 15:41:07 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812920), message_loc: Only, in_order_delivery: false, message_number: 189, timestamp: 18089538, dest_sockid: SocketID(81), payload: b"812920" }, V4(0.0.0.0:0))
Dec 02 15:41:07.622 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet: 812921
Dec 02 15:41:07.622 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812920)]
Dec 02 15:41:07.622 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] lr=812905, buffer=[812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812907]
Dec 02 15:41:07.625 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Got packet: Control { timestamp: 18157583, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812920] }) }
Dec 02 15:41:07.625 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Loss list=[812920]
Dec 02 15:41:07.691 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812920)
Dec 02 15:41:07.691 INFO [stdout] 2018-12-02 15:41:07 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812920), message_loc: Only, in_order_delivery: false, message_number: 189, timestamp: 18089538, dest_sockid: SocketID(81), payload: b"812920" }, V4(0.0.0.0:0))
Dec 02 15:41:07.767 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet: 812922
Dec 02 15:41:07.767 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] lr=812905, buffer=[812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812907]
Dec 02 15:41:07.843 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet: 812923
Dec 02 15:41:07.843 INFO [stdout] 2018-12-02 15:41:07 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812923), message_loc: Only, in_order_delivery: false, message_number: 192, timestamp: 18378612, dest_sockid: SocketID(81), payload: b"812923" }, V4(0.0.0.0:0))
Dec 02 15:41:07.922 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet: 812924
Dec 02 15:41:07.922 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812923)]
Dec 02 15:41:07.922 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::receiver] lr=812905, buffer=[812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812907]
Dec 02 15:41:07.922 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Got packet: Control { timestamp: 18455495, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812923] }) }
Dec 02 15:41:07.922 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Loss list=[812923]
Dec 02 15:41:07.998 INFO [stdout] 2018-12-02 15:41:07 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812923)
Dec 02 15:41:07.998 INFO [stdout] 2018-12-02 15:41:07 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812923), message_loc: Only, in_order_delivery: false, message_number: 192, timestamp: 18378612, dest_sockid: SocketID(81), payload: b"812923" }, V4(0.0.0.0:0))
Dec 02 15:41:08.008 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812906), lr_ack_acked=SeqNumber(812886)
Dec 02 15:41:08.008 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812906, 812908, 812920, 812923] [SeqNumber(812906), SeqNumber(812908)]
Dec 02 15:41:08.008 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812906), SeqNumber(812908)]
Dec 02 15:41:08.008 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Got packet: Control { timestamp: 18543001, dest_sockid: SocketID(13), control_type: Ack(21, AckControlInfo { ack_number: SeqNumber(812906), rtt: Some(330497), rtt_variance: Some(288350), buffer_available: None, packet_recv_rate: Some(17703101), est_link_cap: Some(0) }) }
Dec 02 15:41:08.008 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:41:08.008 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Got packet: Control { timestamp: 18543315, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812906, 812908] }) }
Dec 02 15:41:08.008 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Loss list=[812906, 812908]
Dec 02 15:41:08.086 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812906)
Dec 02 15:41:08.086 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] lr=812905, buffer=[812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812907, 812906]
Dec 02 15:41:08.173 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812908)
Dec 02 15:41:08.173 INFO [stdout] 2018-12-02 15:41:08 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812908), message_loc: Only, in_order_delivery: false, message_number: 177, timestamp: 17228952, dest_sockid: SocketID(81), payload: b"812908" }, V4(0.0.0.0:0))
Dec 02 15:41:08.266 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Sending packet: 812925
Dec 02 15:41:08.266 INFO [stdout] 2018-12-02 15:41:08 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812925), message_loc: Only, in_order_delivery: false, message_number: 194, timestamp: 18797789, dest_sockid: SocketID(81), payload: b"812925" }, V4(0.0.0.0:0))
Dec 02 15:41:08.354 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Sending packet: 812926
Dec 02 15:41:08.358 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] Releasing SeqNumber(812906)
Dec 02 15:41:08.359 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] Releasing SeqNumber(812907)
Dec 02 15:41:08.359 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812925)]
Dec 02 15:41:08.359 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] lr=812907, buffer=[812926, 812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909]
Dec 02 15:41:08.359 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Got packet: Control { timestamp: 18890043, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812925] }) }
Dec 02 15:41:08.359 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Loss list=[812925]
Dec 02 15:41:08.441 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812925)
Dec 02 15:41:08.441 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] lr=812907, buffer=[812926, 812925, 812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909]
Dec 02 15:41:08.541 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Sending packet: 812927
Dec 02 15:41:08.541 INFO [stdout] 2018-12-02 15:41:08 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812927), message_loc: Only, in_order_delivery: false, message_number: 196, timestamp: 19076398, dest_sockid: SocketID(81), payload: b"812927" }, V4(0.0.0.0:0))
Dec 02 15:41:08.641 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Sending packet: 812928
Dec 02 15:41:08.642 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812927)]
Dec 02 15:41:08.642 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] lr=812907, buffer=[812928, 812926, 812925, 812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909]
Dec 02 15:41:08.642 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] lr=812907, buffer=[812929, 812928, 812926, 812925, 812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909]
Dec 02 15:41:08.642 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Got packet: Control { timestamp: 19176865, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812927] }) }
Dec 02 15:41:08.642 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Loss list=[812927]
Dec 02 15:41:08.743 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812927)
Dec 02 15:41:08.743 INFO [stdout] 2018-12-02 15:41:08 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812927), message_loc: Only, in_order_delivery: false, message_number: 196, timestamp: 19076398, dest_sockid: SocketID(81), payload: b"812927" }, V4(0.0.0.0:0))
Dec 02 15:41:08.856 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Sending packet: 812930
Dec 02 15:41:08.856 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] lr=812907, buffer=[812930, 812929, 812928, 812926, 812925, 812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909]
Dec 02 15:41:08.975 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::sender] Sending packet: 812931
Dec 02 15:41:08.975 INFO [stdout] 2018-12-02 15:41:08 INFO  [srt::receiver] lr=812907, buffer=[812931, 812930, 812929, 812928, 812926, 812925, 812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909]
Dec 02 15:41:09.083 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::sender] Sending packet: 812932
Dec 02 15:41:09.083 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::receiver] lr=812907, buffer=[812932, 812931, 812930, 812929, 812928, 812926, 812925, 812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909]
Dec 02 15:41:09.195 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::sender] Sending packet: 812933
Dec 02 15:41:09.195 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::receiver] lr=812907, buffer=[812933, 812932, 812931, 812930, 812929, 812928, 812926, 812925, 812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909]
Dec 02 15:41:09.309 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::sender] Sending packet: 812934
Dec 02 15:41:09.309 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::receiver] lr=812907, buffer=[812934, 812933, 812932, 812931, 812930, 812929, 812928, 812926, 812925, 812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909]
Dec 02 15:41:09.422 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::sender] Sending packet: 812935
Dec 02 15:41:09.423 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::receiver] lr=812907, buffer=[812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812926, 812925, 812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909]
Dec 02 15:41:09.465 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812908), lr_ack_acked=SeqNumber(812906)
Dec 02 15:41:09.465 INFO [stdout] 2018-12-02 15:41:09 INFO  [tests] Dropping packet: (Control { timestamp: 19999677, dest_sockid: SocketID(13), control_type: Ack(22, AckControlInfo { ack_number: SeqNumber(812908), rtt: Some(289300), rtt_variance: Some(288118), buffer_available: None, packet_recv_rate: Some(18980901), est_link_cap: Some(0) }) }, V4(0.0.0.0:0))
Dec 02 15:41:09.465 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812908, 812920, 812923, 812927] [SeqNumber(812908), SeqNumber(812920), SeqNumber(812923), SeqNumber(812927)]
Dec 02 15:41:09.465 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812908), SeqNumber(812920), SeqNumber(812923), SeqNumber(812927)]
Dec 02 15:41:09.467 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::sender] Got packet: Control { timestamp: 19999807, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812908, 812920, 812923, 812927] }) }
Dec 02 15:41:09.467 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::sender] Loss list=[812908, 812920, 812923, 812927]
Dec 02 15:41:09.663 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812908)
Dec 02 15:41:09.663 INFO [stdout] 2018-12-02 15:41:09 INFO  [srt::receiver] lr=812907, buffer=[812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812926, 812925, 812924, 812922, 812921, 812919, 812918, 812917, 812916, 812915, 812914, 812913, 812912, 812911, 812910, 812909, 812908]
Dec 02 15:41:10.008 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812920)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812908)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812909)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812910)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812911)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812912)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812913)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812914)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812915)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812916)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812917)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812918)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812919)
Dec 02 15:41:10.009 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] lr=812919, buffer=[812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812926, 812925, 812924, 812922, 812921, 812920]
Dec 02 15:41:10.311 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812923)
Dec 02 15:41:10.311 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812920)
Dec 02 15:41:10.311 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812921)
Dec 02 15:41:10.311 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812922)
Dec 02 15:41:10.311 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] lr=812922, buffer=[812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812926, 812925, 812924, 812923]
Dec 02 15:41:10.511 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812927)
Dec 02 15:41:10.515 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812923)
Dec 02 15:41:10.515 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812924)
Dec 02 15:41:10.515 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812925)
Dec 02 15:41:10.515 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812926)
Dec 02 15:41:10.515 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] lr=812926, buffer=[812935, 812934, 812933, 812932, 812931, 812930, 812929, 812928, 812927]
Dec 02 15:41:10.785 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::sender] Sending packet: 812936
Dec 02 15:41:10.785 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812927)
Dec 02 15:41:10.785 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812928)
Dec 02 15:41:10.785 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812929)
Dec 02 15:41:10.785 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812930)
Dec 02 15:41:10.785 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812931)
Dec 02 15:41:10.785 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812932)
Dec 02 15:41:10.785 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812933)
Dec 02 15:41:10.785 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812934)
Dec 02 15:41:10.785 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812935)
Dec 02 15:41:10.785 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] lr=812935, buffer=[812936]
Dec 02 15:41:10.924 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Releasing SeqNumber(812936)
Dec 02 15:41:10.924 INFO [stdout] 2018-12-02 15:41:10 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812937), lr_ack_acked=SeqNumber(812906)
Dec 02 15:41:11.079 INFO [stdout] 2018-12-02 15:41:11 INFO  [srt::sender] Got packet: Control { timestamp: 21454714, dest_sockid: SocketID(13), control_type: Ack(23, AckControlInfo { ack_number: SeqNumber(812937), rtt: Some(289300), rtt_variance: Some(288118), buffer_available: None, packet_recv_rate: Some(19801997), est_link_cap: Some(0) }) }
Dec 02 15:41:11.079 INFO [stdout] 2018-12-02 15:41:11 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:41:11.079 INFO [stdout] 2018-12-02 15:41:11 INFO  [srt::sender] Sending packet: 812937
Dec 02 15:41:11.079 INFO [stdout] 2018-12-02 15:41:11 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812937), message_loc: Only, in_order_delivery: false, message_number: 206, timestamp: 21610363, dest_sockid: SocketID(81), payload: b"812937" }, V4(0.0.0.0:0))
Dec 02 15:41:11.767 INFO [stdout] 2018-12-02 15:41:11 INFO  [srt::sender] Sending packet: 812938
Dec 02 15:41:11.767 INFO [stdout] 2018-12-02 15:41:11 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812937)]
Dec 02 15:41:11.767 INFO [stdout] 2018-12-02 15:41:11 INFO  [srt::receiver] lr=812936, buffer=[812938]
Dec 02 15:41:11.767 INFO [stdout] 2018-12-02 15:41:11 INFO  [srt::sender] Got packet: Control { timestamp: 22297987, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812937] }) }
Dec 02 15:41:11.767 INFO [stdout] 2018-12-02 15:41:11 INFO  [srt::sender] Loss list=[812937]
Dec 02 15:41:11.879 INFO [stdout] 2018-12-02 15:41:11 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812937)
Dec 02 15:41:11.879 INFO [stdout] 2018-12-02 15:41:11 INFO  [srt::receiver] lr=812936, buffer=[812938, 812937]
Dec 02 15:41:12.011 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::sender] Sending packet: 812939
Dec 02 15:41:12.011 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::receiver] Releasing SeqNumber(812937)
Dec 02 15:41:12.011 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::receiver] Releasing SeqNumber(812938)
Dec 02 15:41:12.011 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::receiver] lr=812938, buffer=[812939]
Dec 02 15:41:12.147 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::sender] Sending packet: 812940
Dec 02 15:41:12.169 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::receiver] Releasing SeqNumber(812939)
Dec 02 15:41:12.169 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::receiver] lr=812939, buffer=[812940]
Dec 02 15:41:12.270 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::sender] Sending packet: 812941
Dec 02 15:41:12.270 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::receiver] Releasing SeqNumber(812940)
Dec 02 15:41:12.270 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::receiver] lr=812940, buffer=[812941]
Dec 02 15:41:12.397 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::sender] Sending packet: 812942
Dec 02 15:41:12.397 INFO [stdout] 2018-12-02 15:41:12 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812942), message_loc: Only, in_order_delivery: false, message_number: 211, timestamp: 22932179, dest_sockid: SocketID(81), payload: b"812942" }, V4(0.0.0.0:0))
Dec 02 15:41:12.429 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::receiver] Releasing SeqNumber(812941)
Dec 02 15:41:12.429 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812942), lr_ack_acked=SeqNumber(812937)
Dec 02 15:41:12.429 INFO [stdout] 2018-12-02 15:41:12 INFO  [tests] Dropping packet: (Control { timestamp: 22960274, dest_sockid: SocketID(13), control_type: Ack(24, AckControlInfo { ack_number: SeqNumber(812942), rtt: Some(272612), rtt_variance: Some(249169), buffer_available: None, packet_recv_rate: Some(20922329), est_link_cap: Some(0) }) }, V4(0.0.0.0:0))
Dec 02 15:41:12.599 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::sender] Sending packet: 812943
Dec 02 15:41:12.599 INFO [stdout] 2018-12-02 15:41:12 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812943), message_loc: Only, in_order_delivery: false, message_number: 212, timestamp: 23133882, dest_sockid: SocketID(81), payload: b"812943" }, V4(0.0.0.0:0))
Dec 02 15:41:12.759 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::sender] Sending packet: 812944
Dec 02 15:41:12.759 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812942), SeqNumber(812943)]
Dec 02 15:41:12.763 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::receiver] lr=812941, buffer=[812944]
Dec 02 15:41:12.763 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::sender] Got packet: Control { timestamp: 23293998, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [-2146670706, 812943] }) }
Dec 02 15:41:12.763 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::receiver] lr=812941, buffer=[812945, 812944]
Dec 02 15:41:12.763 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::sender] Loss list=[812942, 812943]
Dec 02 15:41:12.943 INFO [stdout] 2018-12-02 15:41:12 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812942)
Dec 02 15:41:12.943 INFO [stdout] 2018-12-02 15:41:12 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812942), message_loc: Only, in_order_delivery: false, message_number: 211, timestamp: 22932179, dest_sockid: SocketID(81), payload: b"812942" }, V4(0.0.0.0:0))
Dec 02 15:41:13.165 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812943)
Dec 02 15:41:13.165 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::receiver] lr=812941, buffer=[812945, 812944, 812943]
Dec 02 15:41:13.615 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::sender] Sending packet: 812946
Dec 02 15:41:13.615 INFO [stdout] 2018-12-02 15:41:13 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812946), message_loc: Only, in_order_delivery: false, message_number: 215, timestamp: 24150111, dest_sockid: SocketID(81), payload: b"812946" }, V4(0.0.0.0:0))
Dec 02 15:41:13.757 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::sender] Sending packet: 812947
Dec 02 15:41:13.758 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812946)]
Dec 02 15:41:13.758 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::receiver] lr=812941, buffer=[812947, 812945, 812944, 812943]
Dec 02 15:41:13.758 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::sender] Got packet: Control { timestamp: 24292947, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812946] }) }
Dec 02 15:41:13.758 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::sender] Loss list=[812946]
Dec 02 15:41:13.779 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812942), lr_ack_acked=SeqNumber(812937)
Dec 02 15:41:13.779 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812942, 812946] [SeqNumber(812942)]
Dec 02 15:41:13.779 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812942)]
Dec 02 15:41:13.779 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::sender] Got packet: Control { timestamp: 24314095, dest_sockid: SocketID(13), control_type: Ack(25, AckControlInfo { ack_number: SeqNumber(812942), rtt: Some(272612), rtt_variance: Some(249169), buffer_available: None, packet_recv_rate: Some(21943285), est_link_cap: Some(0) }) }
Dec 02 15:41:13.779 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:41:13.779 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::sender] Got packet: Control { timestamp: 24314165, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812942] }) }
Dec 02 15:41:13.779 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::sender] Loss list=[812946, 812942]
Dec 02 15:41:13.900 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812946)
Dec 02 15:41:13.903 INFO [stdout] 2018-12-02 15:41:13 INFO  [srt::receiver] lr=812941, buffer=[812947, 812946, 812945, 812944, 812943]
Dec 02 15:41:14.055 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812942)
Dec 02 15:41:14.056 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] lr=812941, buffer=[812947, 812946, 812945, 812944, 812943, 812942]
Dec 02 15:41:14.211 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::sender] Sending packet: 812948
Dec 02 15:41:14.211 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] Releasing SeqNumber(812942)
Dec 02 15:41:14.211 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] Releasing SeqNumber(812943)
Dec 02 15:41:14.211 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] Releasing SeqNumber(812944)
Dec 02 15:41:14.211 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] Releasing SeqNumber(812945)
Dec 02 15:41:14.211 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] Releasing SeqNumber(812946)
Dec 02 15:41:14.211 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] Releasing SeqNumber(812947)
Dec 02 15:41:14.211 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] lr=812947, buffer=[812948]
Dec 02 15:41:14.371 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::sender] Sending packet: 812949
Dec 02 15:41:14.371 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] Releasing SeqNumber(812948)
Dec 02 15:41:14.371 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] lr=812948, buffer=[812949]
Dec 02 15:41:14.531 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::sender] Sending packet: 812950
Dec 02 15:41:14.531 INFO [stdout] 2018-12-02 15:41:14 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812950), message_loc: Only, in_order_delivery: false, message_number: 219, timestamp: 25066212, dest_sockid: SocketID(81), payload: b"812950" }, V4(0.0.0.0:0))
Dec 02 15:41:14.686 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::sender] Sending packet: 812951
Dec 02 15:41:14.687 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] Releasing SeqNumber(812949)
Dec 02 15:41:14.687 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812950)]
Dec 02 15:41:14.687 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] lr=812949, buffer=[812951]
Dec 02 15:41:14.695 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::sender] Got packet: Control { timestamp: 25221927, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812950] }) }
Dec 02 15:41:14.695 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::sender] Loss list=[812950]
Dec 02 15:41:14.993 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812950), lr_ack_acked=SeqNumber(812942)
Dec 02 15:41:14.993 INFO [stdout] 2018-12-02 15:41:14 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812950)
Dec 02 15:41:14.993 INFO [stdout] 2018-12-02 15:41:14 INFO  [tests] Dropping packet: (Data { seq_number: SeqNumber(812950), message_loc: Only, in_order_delivery: false, message_number: 219, timestamp: 25066212, dest_sockid: SocketID(81), payload: b"812950" }, V4(0.0.0.0:0))
Dec 02 15:41:15.023 INFO [stdout] 2018-12-02 15:41:15 INFO  [srt::sender] Got packet: Control { timestamp: 25528003, dest_sockid: SocketID(13), control_type: Ack(26, AckControlInfo { ack_number: SeqNumber(812950), rtt: Some(238577), rtt_variance: Some(249085), buffer_available: None, packet_recv_rate: Some(23350542), est_link_cap: Some(0) }) }
Dec 02 15:41:15.023 INFO [stdout] 2018-12-02 15:41:15 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:41:16.206 INFO [stdout] 2018-12-02 15:41:16 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812950), lr_ack_acked=SeqNumber(812942)
Dec 02 15:41:16.206 INFO [stdout] 2018-12-02 15:41:16 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812950] [SeqNumber(812950)]
Dec 02 15:41:16.206 INFO [stdout] 2018-12-02 15:41:16 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812950)]
Dec 02 15:41:17.420 INFO [stdout] 2018-12-02 15:41:17 INFO  [srt::receiver] Sending ACK; ack_num=SeqNumber(812950), lr_ack_acked=SeqNumber(812942)
Dec 02 15:41:17.420 INFO [stdout] 2018-12-02 15:41:17 INFO  [tests] Dropping packet: (Control { timestamp: 27954729, dest_sockid: SocketID(13), control_type: Ack(28, AckControlInfo { ack_number: SeqNumber(812950), rtt: Some(238577), rtt_variance: Some(249085), buffer_available: None, packet_recv_rate: Some(23350542), est_link_cap: Some(0) }) }, V4(0.0.0.0:0))
Dec 02 15:41:17.420 INFO [stdout] 2018-12-02 15:41:17 INFO  [srt::receiver] Sending NAK with lost packets: ll=[812950] [SeqNumber(812950)]
Dec 02 15:41:17.420 INFO [stdout] 2018-12-02 15:41:17 INFO  [srt::receiver] Sending NAK for=[SeqNumber(812950)]
Dec 02 15:41:17.420 INFO [stdout] 2018-12-02 15:41:17 INFO  [tests] Dropping packet: (Control { timestamp: 27954823, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812950] }) }, V4(0.0.0.0:0))
Dec 02 15:41:17.739 INFO [stderr] thread '<unnamed>' panicked at 'assertion failed: `(left == right)`
Dec 02 15:41:17.739 INFO [stderr]   left: `812950`,
Dec 02 15:41:17.739 INFO [stderr]  right: `1812731`', tests/tests.rs:266:32
Dec 02 15:41:17.739 INFO [stderr] stack backtrace:
Dec 02 15:41:17.764 INFO [stderr]    0:     0x557bb05cf82f - std::sys::unix::backtrace::tracing::imp::unwind_backtrace::h3a8b83e1922cbdde
Dec 02 15:41:17.765 INFO [stderr]                                at libstd/sys/unix/backtrace/tracing/gcc_s.rs:49
Dec 02 15:41:17.765 INFO [stderr]    1:     0x557bb05d8b07 - std::sys_common::backtrace::print::h00d3155a29b4020f
Dec 02 15:41:17.765 INFO [stderr]                                at libstd/sys_common/backtrace.rs:71
Dec 02 15:41:17.765 INFO [stderr]                                at libstd/sys_common/backtrace.rs:59
Dec 02 15:41:17.765 INFO [stderr]    2:     0x557bb05d37ef - std::panicking::default_hook::{{closure}}::hd7465c742e79e1bb
Dec 02 15:41:17.766 INFO [stderr]                                at libstd/panicking.rs:211
Dec 02 15:41:17.767 INFO [stderr]    3:     0x557bb05d3554 - std::panicking::default_hook::h9e3966f2aa2a6e12
Dec 02 15:41:17.767 INFO [stderr]                                at libstd/panicking.rs:227
Dec 02 15:41:17.767 INFO [stdout] 2018-12-02 15:41:17 INFO  [srt::sender] Got packet: Control { timestamp: 26741350, dest_sockid: SocketID(13), control_type: Ack(27, AckControlInfo { ack_number: SeqNumber(812950), rtt: Some(238577), rtt_variance: Some(249085), buffer_available: None, packet_recv_rate: Some(23350542), est_link_cap: Some(0) }) }
Dec 02 15:41:17.767 INFO [stdout] 2018-12-02 15:41:17 INFO  [tests] Dropping packet: (Control { timestamp: 28302087, dest_sockid: SocketID(81), control_type: Ack2(27) }, V4(0.0.0.0:0))
Dec 02 15:41:17.767 INFO [stdout] 2018-12-02 15:41:17 INFO  [srt::default_congestion_ctrl::sender] New window size: 1000
Dec 02 15:41:17.767 INFO [stdout] 2018-12-02 15:41:17 INFO  [srt::sender] Got packet: Control { timestamp: 26741469, dest_sockid: SocketID(13), control_type: Nak(NakControlInfo { loss_info: [812950] }) }
Dec 02 15:41:17.767 INFO [stdout] 2018-12-02 15:41:17 INFO  [srt::sender] Loss list=[812950]
Dec 02 15:41:17.767 INFO [stdout] 2018-12-02 15:41:17 INFO  [srt::sender] Sending packet in loss list, seq=SeqNumber(812950)
Dec 02 15:41:17.767 INFO [stderr]    4:     0x557bb05d3ece - std::panicking::rust_panic_with_hook::h587f091ae747d080
Dec 02 15:41:17.767 INFO [stderr]                                at libstd/panicking.rs:476
Dec 02 15:41:17.767 INFO [stderr]    5:     0x557bb05d3a71 - std::panicking::continue_panic_fmt::hb3a4fdede87f43a8
Dec 02 15:41:17.767 INFO [stderr]                                at libstd/panicking.rs:390
Dec 02 15:41:17.767 INFO [stderr]    6:     0x557bb05d39be - std::panicking::begin_panic_fmt::h387afc7da9a10555
Dec 02 15:41:17.767 INFO [stderr]                                at libstd/panicking.rs:345
Dec 02 15:41:17.767 INFO [stderr]    7:     0x557bb04f8df8 - tests::test_with_loss::{{closure}}::{{closure}}::head7e95934ad3173
Dec 02 15:41:17.768 INFO [stderr]                                at tests/tests.rs:266
Dec 02 15:41:17.768 INFO [stderr]    8:     0x557bb04e3863 - <core::result::Result<T, E>>::map::h939c787a08404d95
Dec 02 15:41:17.768 INFO [stderr]                                at libcore/result.rs:468
Dec 02 15:41:17.768 INFO [stderr]    9:     0x557bb04ec763 - <futures::future::map::Map<A, F> as futures::future::Future>::poll::h3985cb2b7aa3867e
Dec 02 15:41:17.768 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/future/map.rs:35
Dec 02 15:41:17.768 INFO [stderr]   10:     0x557bb051a662 - <futures::task_impl::Spawn<T>>::poll_future_notify::{{closure}}::h1ee8523e972a1457
Dec 02 15:41:17.769 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:326
Dec 02 15:41:17.769 INFO [stderr]   11:     0x557bb051a8cf - <futures::task_impl::Spawn<T>>::enter::{{closure}}::h0cc6ff222487c33a
Dec 02 15:41:17.769 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:396
Dec 02 15:41:17.769 INFO [stderr]   12:     0x557bb0506f0a - futures::task_impl::std::set::h7de0e363d8a1c578
Dec 02 15:41:17.770 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:78
Dec 02 15:41:17.770 INFO [stderr]   13:     0x557bb051a74a - <futures::task_impl::Spawn<T>>::enter::hb636088ea024f53f
Dec 02 15:41:17.770 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:396
Dec 02 15:41:17.770 INFO [stderr]   14:     0x557bb051a443 - <futures::task_impl::Spawn<T>>::poll_fn_notify::h4cd3ba8313d7eb44
Dec 02 15:41:17.770 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:288
Dec 02 15:41:17.770 INFO [stderr]   15:     0x557bb051a616 - <futures::task_impl::Spawn<T>>::poll_future_notify::hec5f67ed346929dd
Dec 02 15:41:17.770 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/mod.rs:326
Dec 02 15:41:17.770 INFO [stderr]   16:     0x557bb050700d - futures::task_impl::std::<impl futures::task_impl::Spawn<F>>::wait_future::{{closure}}::h5fe6b25397fd0bd8
Dec 02 15:41:17.770 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:231
Dec 02 15:41:17.770 INFO [stderr]   17:     0x557bb0506b86 - futures::task_impl::std::ThreadNotify::with_current::{{closure}}::h85218c6dfb2b458f
Dec 02 15:41:17.770 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:478
Dec 02 15:41:17.770 INFO [stderr]   18:     0x557bb04eaa49 - <std::thread::local::LocalKey<T>>::try_with::h184a82dc77a9cd26
Dec 02 15:41:17.770 INFO [stderr]                                at libstd/thread/local.rs:300
Dec 02 15:41:17.770 INFO [stderr]   19:     0x557bb04ea5bc - <std::thread::local::LocalKey<T>>::with::h3e8115f72008ae1a
Dec 02 15:41:17.770 INFO [stderr]                                at libstd/thread/local.rs:254
Dec 02 15:41:17.770 INFO [stderr]   20:     0x557bb0506ac6 - futures::task_impl::std::ThreadNotify::with_current::h0a5b97a4162c7609
Dec 02 15:41:17.770 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:478
Dec 02 15:41:17.770 INFO [stderr]   21:     0x557bb051baec - futures::task_impl::std::<impl futures::task_impl::Spawn<F>>::wait_future::hda11e5a1ed9a9813
Dec 02 15:41:17.770 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/task_impl/std/mod.rs:228
Dec 02 15:41:17.770 INFO [stderr]   22:     0x557bb04ec359 - futures::future::Future::wait::he4da265b841cc26a
Dec 02 15:41:17.770 INFO [stderr]                                at /cargo-home/registry/src/github.com-1ecc6299db9ec823/futures-0.1.25/src/future/mod.rs:299
Dec 02 15:41:17.770 INFO [stderr]   23:     0x557bb04f8ec6 - tests::test_with_loss::{{closure}}::h5ae2942d9c1d8b95
Dec 02 15:41:17.770 INFO [stderr]                                at tests/tests.rs:261
Dec 02 15:41:17.770 INFO [stderr]   24:     0x557bb0503836 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb0e5a5775696fd29
Dec 02 15:41:17.770 INFO [stderr]                                at libstd/sys_common/backtrace.rs:136
Dec 02 15:41:17.770 INFO [stderr]   25:     0x557bb04e89f4 - std::thread::Builder::spawn::{{closure}}::{{closure}}::h6c34fd8c832125b1
Dec 02 15:41:17.770 INFO [stderr]                                at libstd/thread/mod.rs:409
Dec 02 15:41:17.770 INFO [stderr]   26:     0x557bb05033e4 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h762fb1953809cdf0
Dec 02 15:41:17.770 INFO [stderr]                                at libstd/panic.rs:313
Dec 02 15:41:17.770 INFO [stderr]   27:     0x557bb04ef7d9 - std::panicking::try::do_call::hde81227754bb8ee1
Dec 02 15:41:17.770 INFO [stderr]                                at libstd/panicking.rs:310
Dec 02 15:41:17.770 INFO [stderr]   28:     0x557bb05e3ab9 - __rust_maybe_catch_panic
Dec 02 15:41:17.771 INFO [stderr]                                at libpanic_unwind/lib.rs:102
Dec 02 15:41:17.771 INFO [stderr]   29:     0x557bb04ef637 - std::panicking::try::h2b9b34141cef665d
Dec 02 15:41:17.771 INFO [stderr]                                at libstd/panicking.rs:289
Dec 02 15:41:17.771 INFO [stderr]   30:     0x557bb0503876 - std::panic::catch_unwind::h212f9d21d7572af9
Dec 02 15:41:17.772 INFO [stderr]                                at libstd/panic.rs:392
Dec 02 15:41:17.772 INFO [stderr]   31:     0x557bb04e87d2 - std::thread::Builder::spawn::{{closure}}::h96596aae1d150f09
Dec 02 15:41:17.773 INFO [stderr]                                at libstd/thread/mod.rs:408
Dec 02 15:41:17.773 INFO [stderr]   32:     0x557bb04e8cd4 - <F as alloc::boxed::FnBox<A>>::call_box::h721cf1fcc5ab0d3e
Dec 02 15:41:17.775 INFO [stderr]                                at liballoc/boxed.rs:672
Dec 02 15:41:17.776 INFO [stderr]   33:     0x557bb05d16dd - std::sys_common::thread::start_thread::h4c28257d67cdf9cb
Dec 02 15:41:17.779 INFO [stderr]                                at liballoc/boxed.rs:682
Dec 02 15:41:17.781 INFO [stderr]                                at libstd/sys_common/thread.rs:24
Dec 02 15:41:17.782 INFO [stderr]   34:     0x557bb05c4755 - std::sys::unix::thread::Thread::new::thread_start::hfecb2e7eff8fd34f
Dec 02 15:41:17.784 INFO [stderr]                                at libstd/sys/unix/thread.rs:90
Dec 02 15:41:17.785 INFO [stderr]   35:     0x7f0c562d96b9 - start_thread
Dec 02 15:41:17.786 INFO [stderr]   36:     0x7f0c55df941c - clone
Dec 02 15:41:17.788 INFO [stderr]   37:                0x0 - <unknown>
Dec 02 15:41:17.803 INFO [stdout] test test_with_loss ... ok
Dec 02 15:41:17.803 INFO [stdout] 
Dec 02 15:41:17.804 INFO [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 15:41:17.804 INFO [stdout] 
Dec 02 15:41:17.806 INFO [stderr]    Doc-tests srt
Dec 02 15:41:23.576 INFO [stdout] 
Dec 02 15:41:23.576 INFO [stdout] running 0 tests
Dec 02 15:41:23.576 INFO [stdout] 
Dec 02 15:41:23.576 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 02 15:41:23.576 INFO [stdout] 
Dec 02 15:41:23.587 INFO [stderr] su: No module specific data is present
Dec 02 15:41:25.067 INFO running `"docker" "inspect" "d64c72c5b9a18978bd3b5f9998e671b181c4a8cb2f39972fb4696793ce55c9a9"`
Dec 02 15:41:25.383 INFO running `"docker" "rm" "-f" "d64c72c5b9a18978bd3b5f9998e671b181c4a8cb2f39972fb4696793ce55c9a9"`
Dec 02 15:41:25.802 INFO [stdout] d64c72c5b9a18978bd3b5f9998e671b181c4a8cb2f39972fb4696793ce55c9a9
